Android虚拟AB升级流程简述及升级完整log

本文对 Android update_engine 升级流程进行粗略的分析（详细流程，可以参考 log 对代码进行分析，主要梳理清楚主要类的关系即可搞懂升级流程，该 log 为本地通过update_engine_client 进行升级产生的）：

1、Java层

主要给应用提供bind()、applyPayload() 、onStatusUpdate()、onPayloadApplicationComplete()接口，用来bind 到 update_engine 服务、开始升级、获取升级进度、获取升级结果等。

frameworks/base/core/java/android/os/UpdateEngine.java

    /**
     * Creates a new instance.
     */
    public UpdateEngine() {
        mUpdateEngine = IUpdateEngine.Stub.asInterface(
                ServiceManager.getService(UPDATE_ENGINE_SERVICE));
    }

    /**
     * Prepares this instance for use. The callback will be notified on any
     * status change, and when the update completes. A handler can be supplied
     * to control which thread runs the callback, or null.
     */
    public boolean bind(final UpdateEngineCallback callback, final Handler handler) {
        synchronized (mUpdateEngineCallbackLock) {
            mUpdateEngineCallback = new IUpdateEngineCallback.Stub() {
                @Override
                public void onStatusUpdate(final int status, final float percent) {
                    if (handler != null) {
                        handler.post(new Runnable() {
                            @Override
                            public void run() {
                                callback.onStatusUpdate(status, percent);
                            }
                        });
                    } else {
                        callback.onStatusUpdate(status, percent);
                    }
                }

                @Override
                public void onPayloadApplicationComplete(final int errorCode) {
                    if (handler != null) {
                        handler.post(new Runnable() {
                            @Override
                            public void run() {
                                callback.onPayloadApplicationComplete(errorCode);
                            }
                        });
                    } else {
                        callback.onPayloadApplicationComplete(errorCode);
                    }
                }
            };

            try {
                return mUpdateEngine.bind(mUpdateEngineCallback);
            } catch (RemoteException e) {
                throw e.rethrowFromSystemServer();
            }
        }
    }


    /**
     * Equivalent to {@code bind(callback, null)}.
     */
    public boolean bind(final UpdateEngineCallback callback) {
        return bind(callback, null);
    }

    public void applyPayload(String url, long offset, long size, String[] headerKeyValuePairs) {
        try {
            mUpdateEngine.applyPayload(url, offset, size, headerKeyValuePairs);
        } catch (RemoteException e) {
            throw e.rethrowFromSystemServer();
        }
    }

frameworks/base/core/java/android/os/UpdateEngineCallback.java

@SystemApi
public abstract class UpdateEngineCallback {

    /**
     * Invoked when anything changes. The value of {@code status} will
     * be one of the values from {@link UpdateEngine.UpdateStatusConstants},
     * and {@code percent} will be valid [TODO: in which cases?].
     */
    public abstract void onStatusUpdate(int status, float percent);

    /**
     * Invoked when the payload has been applied, whether successfully or
     * unsuccessfully. The value of {@code errorCode} will be one of the
     * values from {@link UpdateEngine.ErrorCodeConstants}.
     */
    public abstract void onPayloadApplicationComplete(
            @UpdateEngine.ErrorCode int errorCode);
}

2、native 层

update_engine 初始化:

Main.cc(system\update_engine)
  // update_engine 服务入口，该服务由 init.rc中启动
  int main(int argc, char** argv) {
    chromeos_update_engine::Terminator::Init();
    brillo::FlagHelper::Init(argc, argv, "A/B Update Engine");

    LOG(INFO) << "A/B Update Engine starting";

    chromeos_update_engine::UpdateEngineDaemon update_engine_daemon;
    // 注意 UpdateEngineDaemon 继承于 brillo::Daemon (class UpdateEngineDaemon : public brillo::Daemon)，
    // 且 UpdateEngineDaemon 没有实现 Run()方法，则调用父类的Run()方法
    int exit_code = update_engine_daemon.Run();

    LOG(INFO) << "A/B Update Engine terminating with exit code " << exit_code;
    return exit_code;
  }


Daemon.cc (external\libbrillo\brillo\daemons)
  int Daemon::Run() {
    // !!!重要, 这里OnInit()方法,调用Daemon类的还是UpdateEngineDaemon的? 实际从UpdateEngineDaemon
    // 调用的 Run()方法，而且 子类 进行了重写，所以这里实际调到 UpdateEngineDaemon中
    int exit_code = OnInit();

    message_loop_.PostTask(
          base::Bind(&Daemon::OnEventLoopStartedTask, base::Unretained(this)));
    message_loop_.Run();

    OnShutdown(&exit_code_);
    while (message_loop_.RunOnce(false /* may_block */)) {}

    return exit_code_;
  }
     
  int Daemon::OnInit() {
    async_signal_handler_.Init();
    for (int signal : {SIGTERM, SIGINT}) {
      async_signal_handler_.RegisterHandler(signal, base::Bind(&Daemon::Shutdown, base::Unretained(this)));
    }
     
    async_signal_handler_.RegisterHandler(SIGHUP, base::Bind(&Daemon::Restart, base::Unretained(this)));
      
    return EX_OK;
  }


Daemon.h (external\libbrillo\brillo\daemons)
  class BRILLO_EXPORT Daemon : public AsynchronousSignalHandlerInterface {
    public:
          virtual int Run();
  }


Daemon.h (system\update_engine)
// UpdateEngineDaemon继承于Daemon,但是该类本身没有实现Run()方法, 只能使用父类的方法,所以实际调用到
// Daemon::Run()中，而实际Daemon中Run方法先执行OnInit(),
    class UpdateEngineDaemon : public brillo::Daemon {
      protected:
        int OnInit() override;
      private:
        brillo::BinderWatcher binder_watcher_;
        android::sp<BinderUpdateEngineAndroidService> binder_service_;
        std::unique_ptr<DaemonStateInterface> daemon_state_;
    }


Daemon.cc (system\update_engine)
  int UpdateEngineDaemon::OnInit() {
      int exit_code = Daemon::OnInit();
    
      DaemonStateAndroid* daemon_state_android = new DaemonStateAndroid();
      daemon_state_.reset(daemon_state_android);
      LOG_IF(ERROR, !daemon_state_android->Initialize()) << "Failed to initialize system state.";
    
      binder_service_ = new BinderUpdateEngineAndroidService{ daemon_state_android->service_delegate()};
    
      auto binder_wrapper = android::BinderWrapper::Get();
      // 注意 system/update_engine/binder_service_android.h : const char* ServiceName() 
      // const { return "android.os.UpdateEngineService";} 所以RegisterService
      // 第一个参数为 "android.os.UpdateEngineService", 所以java层通过该service同update_engine进行通信,
      // 而不是update_engine_client所以简单来说通过update_engine.rc启动update_engine服务, 该服务里面注册了
      // "android.os.UpdateEngineService"服务,java层通过该Service名字通信
      if (!binder_wrapper->RegisterService(binder_service_->ServiceName(),binder_service_))

      daemon_state_->AddObserver(binder_service_.get());

      daemon_state_->StartUpdater();
      return EX_OK;
    }
    //UpdateEngineDaemon::OnInit() -> DaemonStateAndroid:Initialize()方法
    

Daemon_state_android.cc (system\update_engine)
    // 创建boot_control_, 判断hardware信息, update_attempter_初始化等
    bool DaemonStateAndroid::Initialize() {
      boot_control_ = boot_control::CreateBootControl();

      hardware_ = hardware::CreateHardware();

      update_attempter_.reset(new UpdateAttempterAndroid(
        this, prefs_.get(), boot_control_.get(), hardware_.get()));

      return true;
    }

    bool DaemonStateAndroid::StartUpdater() {
      // The DaemonState in Android is a passive daemon. It will only start applying
      // an update when instructed to do so from the exposed binder API.
      update_attempter_->Init();
      return true;
    }

    void DaemonStateAndroid::AddObserver(ServiceObserverInterface* observer) {
      service_observers_.insert(observer);
    }

    ServiceDelegateAndroidInterface* DaemonStateAndroid::service_delegate() {
      return update_attempter_.get();
    }

Applypayload流程:

system/update_engine/aosp/update_attempter_android.cc


bool UpdateAttempterAndroid::ApplyPayload(const string& payload_url,int64_t payload_offset, int64_t payload_size,const vector<string>& key_value_pair_headers,brillo::ErrorPtr* error) {

    if (status_ == UpdateStatus::UPDATED_NEED_REBOOT) {
      return LogAndSetError(error, FROM_HERE, "An update already applied, waiting for reboot");
    }

    if (processor_->IsRunning()) {
       LogAndSetError(error, FROM_HERE, "Already processing an update, cancel it first.");
    }
    
     install_plan_ = InstallPlan();
     LOG(INFO) << "Using this install plan:";
     install_plan_.Dump();

     HttpFetcher* fetcher = nullptr;
     fetcher = new FileFetcher();
     BuildUpdateActions(fetcher);
     
     SetStatusAndNotify(UpdateStatus::UPDATE_AVAILABLE);

     UpdatePrefsOnUpdateStart(install_plan_.is_resume);


     ScheduleProcessingStart();
  }

void UpdateAttempterAndroid::BuildUpdateActions(HttpFetcher* fetcher) {
    processor_->set_delegate(this);
    auto update_boot_flags_action = std::make_unique<UpdateBootFlagsAction>(boot_control_);

    auto cleanup_previous_update_action = boot_control_->GetDynamicPartitionControl()
          ->GetCleanupPreviousUpdateAction(boot_control_, prefs_, this);

    auto install_plan_action = std::make_unique<InstallPlanAction>(install_plan_);
  
    auto download_action = std::make_unique<DownloadAction>(prefs_,
                                       boot_control_,
                                       hardware_,
                                       nullptr,  // system_state, not used.
                                       fetcher,  // passes ownership
                                       true /* interactive */);
    download_action->set_delegate(this);
    download_action->set_base_offset(base_offset_);

    auto filesystem_verifier_action = std::make_unique<FilesystemVerifierAction>();
    auto postinstall_runner_action = std::make_unique<PostinstallRunnerAction>(boot_control_, hardware_);
    postinstall_runner_action->set_delegate(this);

    // Bond them together. We have to use the leaf-types when calling BondActions().
    BondActions(install_plan_action.get(), download_action.get());
    BondActions(download_action.get(), filesystem_verifier_action.get());
    BondActions(filesystem_verifier_action.get(),postinstall_runner_action.get());
 
    //将这6个Action放入processor中
    processor_->EnqueueAction(std::move(update_boot_flags_action));
    processor_->EnqueueAction(std::move(cleanup_previous_update_action));
    processor_->EnqueueAction(std::move(install_plan_action));
    processor_->EnqueueAction(std::move(download_action));
    processor_->EnqueueAction(std::move(filesystem_verifier_action));
    processor_->EnqueueAction(std::move(postinstall_runner_action));
  }

// EnqueueAction() 实际把这 6 个 ActionProcessor push 到本地的 actions_(std::deque<std::unique_ptr<AbstractAction>> actions_)中，
// 在StartProcessing() 时从中读取数据
void ActionProcessor::EnqueueAction(unique_ptr<AbstractAction> action) {
  action->SetProcessor(this);
  actions_.push_back(std::move(action));
}



void UpdateAttempterAndroid::ScheduleProcessingStart() {
  LOG(INFO) << "Scheduling an action processor start.";
  // 升级通过Action_Processor，到各个action中进行执行：如下action按顺序执行
  brillo::MessageLoop::current()->PostTask(
      FROM_HERE,
      Bind([](ActionProcessor* processor) { processor->StartProcessing(); },
           base::Unretained(processor_.get())));
}

system/update_engine/common/action_processor.cc

    void ActionProcessor::StartProcessing() {
      if (!actions_.empty()) {
        // 从actions_中取出各个action,action对应的信息,如上log所示:
        current_action_ = std::move(actions_.front());
        actions_.pop_front();
        LOG(INFO) << "ActionProcessor: starting " << current_action_->Type();

        current_action_->PerformAction();
      }
    }

// 截取的部分日志:
11-25 11:45:40.972   839   839 I update_engine: [INFO:action_processor.cc(51)] ActionProcessor: starting UpdateBootFlagsAction
11-25 11:45:41.004   839   839 I update_engine: [INFO:action_processor.cc(143)] ActionProcessor: starting CleanupPreviousUpdateAction
11-25 11:45:41.047   839   839 I update_engine: [INFO:action_processor.cc(143)] ActionProcessor: starting InstallPlanAction
11-25 11:45:41.055   839   839 I update_engine: [INFO:action_processor.cc(143)] ActionProcessor: starting DownloadAction
11-25 11:49:46.429   839   839 I update_engine: [INFO:action_processor.cc(143)] ActionProcessor: starting FilesystemVerifierAction
11-25 11:50:10.660   839   839 I update_engine: [INFO:action_processor.cc(143)] ActionProcessor: starting PostinstallRunnerAction

// 另外比较重要是各个delegate进行调用：
// delegate_变量关系分析:
// 关于delegate_变量定义, 多个文件中的方法中通过delegate_->func()调用,下面梳理下对应关系: 可以看到DownloadAction类中
// 定义了delegate_,且类型为DownloadActionDelegate* 同样HttpFetcher类定义了deleagte_, 且类型为HttpFetcherDelegate*

Action_processor.h (system\update_engine\common):  ActionProcessorDelegate* delegate_{nullptr};

Cleanup_previous_update_action.h (system\update_engine):  CleanupPreviousUpdateActionDelegateInterface* delegate_;

Download_action.h (system\update_engine\payload_consumer):  DownloadActionDelegate* delegate_;

Http_fetcher.h (system\update_engine\common):  HttpFetcherDelegate* delegate_;
    Postinstall_runner_action.h (system\update_engine\payload_consumer):  DelegateInterface* delegate_{nullptr};
  
// 这么多delegate_如何使用,有如何的联系,且看下面set_delegate()方法的地方:

Download_action.cc (system\update_engine\payload_consumer):  http_fetcher_->set_delegate(this);

Multi_range_http_fetcher.cc (system\update_engine\common):  base_fetcher_->set_delegate(this);

Update_attempter_android.cc (system\update_engine):  processor_->set_delegate(nullptr);

Update_attempter_android.cc (system\update_engine):  processor_->set_delegate(this);

Update_attempter_android.cc (system\update_engine):  download_action->set_delegate(this);

Update_attempter_android.cc (system\update_engine):  postinstall_runner_action->set_delegate(this);

Update_attempter_android.cc (system\update_engine):  processor_->set_delegate(this);

// 而各个类中set_delegate()方法如下, 可以看出该方法A-set_delegate(B) 实际将A对象中的delegate_指针指向 B,
// 方便在后面A中执行delegate_->func()时, 从而执行到B中的方法.  

// 比如 DownloadAction对象中, http_fetcher_->set_delegate(this), 那么后续http_fetcher(实际为
// MultiRangeHttpFetcher)中执行delegate_->func()时, 会调用到DownloadAction
// 中的方法. 该处非常重要,理解了这里,那么几个对象之间的关系,就容易理清楚了.

void set_delegate(ActionProcessorDelegate* delegate) { delegate_ = delegate; }
void set_delegate(DownloadActionDelegate* delegate) { delegate_ = delegate; }
void set_delegate(HttpFetcherDelegate* delegate) { delegate_ = delegate; }
void set_delegate(DelegateInterface* delegate) { delegate_ = delegate; }



void DownloadAction::PerformAction(){
  //同样这里比较重要, 执行该方法后, http_fetcher_对象里面的delegate_ = 
  DownloadAction(this);
  //那么http_fetcher_对象即MultiRangeHttpFetcher里面的delegate_->操作,都调用到 DownloadAction里面的方法;
  
  http_fetcher_->set_delegate(this);

  install_plan_.Dump();
  LOG(INFO) << "Marking new slot as unbootable";
  if (!boot_control_->MarkSlotUnbootable(install_plan_.target_slot)){
    LOG(WARNING) << "Unable to mark new slot "
       << BootControlInterface::SlotName(install_plan_.target_slot)
       << ". Proceeding with the update anyway.";
  }
      
  StartDownloading();
}



Multi_range_http_fetcher.cc (system\update_engine\common)
     bool MultiRangeHttpFetcher::ReceivedBytes(HttpFetcher* fetcher,const void* bytes,size_t length) {
       //根据前面分析可知, 在DownloadAtion中通过http_fetcher_->set_delegate(this);设置
       //所以在MultiRangeHttpFetcher中,delegate_实际调用 DownloadAction中方法.
       if (delegate_ && !delegate_->ReceivedBytes(this, bytes, next_size))
         return false;

       //ReceivedBytes处理完, 才会走到这里.
       if (range.HasLength() && bytes_received_this_range_ >= range.length()) {
          LOG(INFO) << "Terminating transfer.";
          fetcher->TerminateTransfer();
        }
     }
     void MultiRangeHttpFetcher::TransferTerminated(HttpFetcher* fetcher) {
       LOG(INFO) << "Received transfer terminated.";
       TransferEnded(fetcher, false);
     }


void MultiRangeHttpFetcher::TransferEnded(HttpFetcher* fetcher,bool successful) {
  http_response_code_ = fetcher->http_response_code();
  LOG(INFO) << "TransferEnded w/ code " << http_response_code_;
       
  LOG(INFO) << "Done w/ all transfers";
    
  if (delegate_)
    delegate_->TransferComplete(this, successful);
    //同样这里的delegate_-> 调用到 DownloadAction中的方法
}

3、升级完整日志

11-25 11:45:40.929   839   839 I update_engine: [INFO:update_attempter_android.cc(280)] Using this install plan:
11-25 11:45:40.938   839   839 I update_engine: [INFO:install_plan.cc(91)] InstallPlan: new_update, version: , source_slot: A, target_slot: B, url: file:///data/ota_package/update.zip, payload: (size: 1408047432, metadata_size: 86042, metadata signature: , hash: 6AF24E671F9FF99FFE7C4A0B5A5FD9FABC22D3B40B712A6079C64DBAE76F5B44, payload type: unknown), hash_checks_mandatory: false, powerwash_required: false, switch_slot_on_reboot: true, run_post_install: true, is_rollback: false, write_verity: true
11-25 11:45:40.945   839   839 I update_engine: [INFO:metrics_utils.cc(363)] Number of Reboots during current update attempt = 0
11-25 11:45:40.951   839   839 I update_engine: [INFO:metrics_utils.cc(371)] Payload Attempt Number = 1
11-25 11:45:40.958   839   839 I update_engine: [INFO:metrics_utils.cc(388)] Update Monotonic Timestamp Start = 1/1/1970 0:11:13 GMT
11-25 11:45:40.963   839   839 I update_engine: [INFO:metrics_utils.cc(397)] Update Boot Timestamp Start = 1/1/1970 0:11:13 GMT
11-25 11:45:40.966   839   839 I update_engine: [INFO:update_attempter_android.cc(648)] Scheduling an action processor start.
11-25 11:45:40.971  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_IDLE (0), 0)


11-25 11:45:40.972   839   839 I update_engine: [INFO:action_processor.cc(51)] ActionProcessor: starting UpdateBootFlagsAction
11-25 11:45:40.972  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_UPDATE_AVAILABLE (2), 0)
11-25 11:45:40.977   839   839 I update_engine: [INFO:update_boot_flags_action.cc(45)] Marking booted slot as good.
11-25 11:45:40.998   839   839 I update_engine: [INFO:action_processor.cc(116)] ActionProcessor: finished UpdateBootFlagsAction with code ErrorCode::kSuccess
11-25 11:45:40.998  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_CLEANUP_PREVIOUS_UPDATE (11), 0)
11-25 11:45:41.004   839   839 I update_engine: [INFO:action_processor.cc(143)] ActionProcessor: starting CleanupPreviousUpdateAction
11-25 11:45:41.008   839   839 I update_engine: [INFO:cleanup_previous_update_action.cc(82)] Starting/resuming CleanupPreviousUpdateAction
11-25 11:45:41.012   839   839 I update_engine: [INFO:cleanup_previous_update_action.cc(137)] Boot completed, waiting on markBootSuccessful()
11-25 11:45:41.016   839   839 I update_engine: EnsureMetadataMounted does nothing in Android mode.
11-25 11:45:41.020   839   839 I update_engine: Read merge statistics file failed: No such file or directory
11-25 11:45:41.027   839   839 I update_engine: [INFO:cleanup_previous_update_action.cc(206)] Waiting for any previous merge request to complete. This can take up to several minutes.
11-25 11:45:41.034   839   839 E update_engine: [ERROR:cleanup_previous_update_action.cc(240)] Previous update has not been completed, not cleaning up
11-25 11:45:41.039   839   839 I update_engine: [INFO:cleanup_previous_update_action.cc(397)] Not reporting merge stats because state is Initiated
11-25 11:45:41.043   839   839 I update_engine: [INFO:action_processor.cc(116)] ActionProcessor: finished CleanupPreviousUpdateAction with code ErrorCode::kSuccess
11-25 11:45:41.047   839   839 I update_engine: [INFO:action_processor.cc(143)] ActionProcessor: starting InstallPlanAction
11-25 11:45:41.051   839   839 I update_engine: [INFO:action_processor.cc(116)] ActionProcessor: finished InstallPlanAction with code ErrorCode::kSuccess
11-25 11:45:41.055   839   839 I update_engine: [INFO:action_processor.cc(143)] ActionProcessor: starting DownloadAction
11-25 11:45:41.059   839   839 I update_engine: [INFO:install_plan.cc(91)] InstallPlan: new_update, version: , source_slot: A, target_slot: B, url: file:///data/ota_package/update.zip, payload: (size: 1408047432, metadata_size: 86042, metadata signature: , hash: 6AF24E671F9FF99FFE7C4A0B5A5FD9FABC22D3B40B712A6079C64DBAE76F5B44, payload type: unknown), hash_checks_mandatory: false, powerwash_required: false, switch_slot_on_reboot: true, run_post_install: true, is_rollback: false, write_verity: true
11-25 11:45:41.062   839   839 I update_engine: [INFO:download_action.cc(199)] Marking new slot as unbootable
11-25 11:45:41.066   839   839 E update_engine: [ERROR:boot_control_android.cc(116)] Unable to mark slot B as unbootable: Operation failed
11-25 11:45:41.070   839   839 W update_engine: [WARNING:download_action.cc(201)] Unable to mark new slot B. Proceeding with the update anyway.
11-25 11:45:41.074   839   839 I update_engine: [INFO:multi_range_http_fetcher.cc(45)] starting first transfer
11-25 11:45:41.078   839   839 I update_engine: [INFO:multi_range_http_fetcher.cc(74)] starting transfer of range 2081+1408047432
11-25 11:45:41.085  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 1.31139e-05)
11-25 11:45:41.087   839   839 I update_engine: [INFO:delta_performer.cc(209)] Completed 0/? operations, 16384/1408047432 bytes downloaded (0%), overall progress 0%
11-25 11:45:41.096   839   839 I update_engine: [INFO:delta_performer.cc(520)] Manifest size in payload matches expected value from Omaha
11-25 11:45:41.101   839   839 I update_engine: [INFO:delta_performer.cc(1693)] Verifying using certificates: /system/etc/security/otacerts.zip
11-25 11:45:41.108   839   839 I update_engine: [INFO:payload_verifier.cc(102)] signature blob size = 267
11-25 11:45:41.112   839   839 I update_engine: [INFO:payload_verifier.cc(118)] Truncating the signature to its unpadded size: 256.
11-25 11:45:41.116   839   839 I update_engine: [INFO:payload_verifier.cc(129)] Verified correct signature 1 out of 1 signatures.
11-25 11:45:41.121   839   839 I update_engine: [INFO:payload_metadata.cc(224)] Metadata hash signature matches value in Omaha response.
11-25 11:45:41.133   839   839 I update_engine: [INFO:delta_performer.cc(1728)] Detected a 'full' payload.
11-25 11:45:41.145   839   839 I update_engine: [INFO:delta_performer.cc(985)] Preparing partitions for new update. last hash = , new hash = avJOZx+f+Z/+fEoLWl/Z+rwi07QLcSpgecZNuudvW0Q=eWsiz/DKLC/QScxAw8LIM9T1RR1+WobOfhO+zbhVgi8=
11-25 11:45:41.160   839   839 W update_engine: type=1400 audit(0.0:198): avc: denied { search } for name="/" dev="dm-6" ino=2 scontext=u:r:update_engine:s0 tcontext=u:object_r:unlabeled:s0 tclass=dir permissive=0
11-25 11:45:41.170   839   839 I chatty  : uid=0(root) /system/bin/update_engine identical 5 lines
11-25 11:45:41.170   839   839 W update_engine: type=1400 audit(0.0:204): avc: denied { search } for name="/" dev="dm-6" ino=2 scontext=u:r:update_engine:s0 tcontext=u:object_r:unlabeled:s0 tclass=dir permissive=0
11-25 11:45:41.251   839   839 I update_engine: EnsureMetadataMounted does nothing in Android mode.
11-25 11:45:41.256   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(676)] Erasing AVB footer of system_other partition before update.
11-25 11:45:41.262   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(577)] AVB is not enabled on system_other. Skip erasing.
11-25 11:45:41.267   839   839 I update_engine: Update has been initiated, now canceling
11-25 11:45:41.270   839   839 I update_engine: Removing all update state.
11-25 11:45:41.274   839   839 W update_engine: Cannot read /metadata/ota/snapshot-boot: No such file or directory
11-25 11:45:41.276   839   839 W update_engine: Failed to get flashing status
11-25 11:45:41.279   839   839 W update_engine: Cannot read /metadata/ota/snapshot-boot: No such file or directory
11-25 11:45:42.349   839   839 I update_engine: Successfully unmapped snapshot system_b
11-25 11:45:42.356   839   839 I update_engine: Successfully unmapped snapshot hmd_custom_amx_b
11-25 11:45:42.361   839   839 I update_engine: Successfully unmapped snapshot vendor_b
11-25 11:45:42.366   839   839 I update_engine: Successfully unmapped snapshot product_b
11-25 11:45:42.400   839   839 W update_engine: type=1400 audit(0.0:205): avc: denied { search } for name="/" dev="dm-6" ino=2 scontext=u:r:update_engine:s0 tcontext=u:object_r:unlabeled:s0 tclass=dir permissive=0
11-25 11:45:42.410   839   839 I chatty  : uid=0(root) /system/bin/update_engine identical 4 lines
11-25 11:45:42.420   839   839 W update_engine: type=1400 audit(0.0:210): avc: denied { search } for name="/" dev="dm-6" ino=2 scontext=u:r:update_engine:s0 tcontext=u:object_r:unlabeled:s0 tclass=dir permissive=0
11-25 11:45:42.510   839   839 I update_engine: [liblp]Partition vendor_b will resize from 274493440 bytes to 274477056 bytes
11-25 11:45:42.514   839   839 I update_engine: [liblp]Partition product_b will resize from 1283309568 bytes to 1281126400 bytes
11-25 11:45:42.517   839   839 I update_engine: [liblp]Partition system_b will resize from 1319051264 bytes to 1319157760 bytes
11-25 11:45:42.520   839   839 I update_engine: Remaining free space for COW: 6139273216 bytes
11-25 11:45:42.550   839   839 I update_engine: For partition hmd_custom_amx_b, device size = 109522944, snapshot size = 109522944, cow partition size = 109957120, cow file size = 0
11-25 11:45:42.556   839   839 I update_engine: [liblp]Partition hmd_custom_amx_b-cow will resize from 0 bytes to 109957120 bytes
11-25 11:45:42.559   839   839 I update_engine: Successfully created snapshot partition for hmd_custom_amx_b
11-25 11:45:42.561   839   839 I update_engine: Remaining free space for COW: 6029316096 bytes
11-25 11:45:42.629   839   839 I update_engine: For partition vendor_b, device size = 274477056, snapshot size = 274477056, cow partition size = 275554304, cow file size = 0
11-25 11:45:42.635   839   839 I update_engine: [liblp]Partition vendor_b-cow will resize from 0 bytes to 275554304 bytes
11-25 11:45:42.638   839   839 I update_engine: Successfully created snapshot partition for vendor_b
11-25 11:45:42.641   839   839 I update_engine: Remaining free space for COW: 5753761792 bytes
11-25 11:45:42.949   839   839 I update_engine: For partition product_b, device size = 1281126400, snapshot size = 1281126400, cow partition size = 1286135808, cow file size = 0
11-25 11:45:42.961   839   839 I update_engine: [liblp]Partition product_b-cow will resize from 0 bytes to 1286135808 bytes
11-25 11:45:42.964   839   839 I update_engine: Successfully created snapshot partition for product_b
11-25 11:45:42.967   839   839 I update_engine: Remaining free space for COW: 4467625984 bytes
11-25 11:45:43.282   839   839 I update_engine: For partition system_b, device size = 1319157760, snapshot size = 1319157760, cow partition size = 1324318720, cow file size = 0
11-25 11:45:43.293   839   839 I update_engine: [liblp]Partition system_b-cow will resize from 0 bytes to 1324318720 bytes
11-25 11:45:43.296   839   839 I update_engine: Successfully created snapshot partition for system_b
11-25 11:45:43.299   839   839 I update_engine: Allocating CoW images.
11-25 11:45:43.303   839   839 I update_engine: Successfully created snapshot for hmd_custom_amx_b
11-25 11:45:43.306   839   839 I update_engine: Successfully created snapshot for product_b
11-25 11:45:43.309   839   839 I update_engine: Successfully created snapshot for system_b
11-25 11:45:43.311   839   839 I update_engine: Successfully created snapshot for vendor_b
11-25 11:45:43.315   839   839 I update_engine: Successfully unmapped snapshot hmd_custom_amx_b
11-25 11:45:43.362   839   839 I update_engine: Mapped COW device for hmd_custom_amx_b at /dev/block/dm-4
11-25 11:45:43.369   839   839 I update_engine: Zero-filling COW device: /dev/block/dm-4
11-25 11:45:43.421   839   839 I update_engine: Successfully unmapped snapshot vendor_b
11-25 11:45:43.470   839   839 I update_engine: Mapped COW device for vendor_b at /dev/block/dm-4
11-25 11:45:43.475   839   839 I update_engine: Zero-filling COW device: /dev/block/dm-4
11-25 11:45:43.511   839   839 I update_engine: Successfully unmapped snapshot product_b
11-25 11:45:43.543   839   839 I update_engine: Mapped COW device for product_b at /dev/block/dm-4
11-25 11:45:43.549   839   839 I update_engine: Zero-filling COW device: /dev/block/dm-4
11-25 11:45:43.591   839   839 I update_engine: Successfully unmapped snapshot system_b
11-25 11:45:43.622   839   839 I update_engine: Mapped COW device for system_b at /dev/block/dm-4
11-25 11:45:43.628   839   839 I update_engine: Zero-filling COW device: /dev/block/dm-4
11-25 11:45:43.685   839   839 I update_engine: [liblp]Updated logical partition table at slot 1 on device super
11-25 11:45:43.690   839   839 I update_engine: Successfully created all snapshots for target slot _b
11-25 11:45:43.694   839   839 I update_engine: [INFO:delta_performer.cc(1002)] PreparePartitionsForUpdate done.
11-25 11:45:43.711   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.716   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] boot_b is not in super partition metadata.
11-25 11:45:43.721   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.727   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.732   839   839 I update_engine: Successfully unmapped snapshot system_b
11-25 11:45:43.760   839   839 I update_engine: [libfs_mgr]Created logical partition system_b-base on device /dev/block/dm-4
11-25 11:45:43.793   839   839 I update_engine: Mapped COW device for system_b at /dev/block/dm-11
11-25 11:45:43.833   839   839 I update_engine: Mapped system_b as snapshot device at /dev/block/dm-12
11-25 11:45:43.839   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(173)] Succesfully mapped system_b to device mapper (force_writable = 1); device path at /dev/block/dm-12
11-25 11:45:43.846   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.850   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] lk_b is not in super partition metadata.
11-25 11:45:43.854   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.860   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.865   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] preloader_b is not in super partition metadata.
11-25 11:45:43.869   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.876   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.879   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] logo_b is not in super partition metadata.
11-25 11:45:43.884   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.890   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.894   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] md1img_b is not in super partition metadata.
11-25 11:45:43.911   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.917   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.921   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] spmfw_b is not in super partition metadata.
11-25 11:45:43.926   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.932   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.937   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] scp_b is not in super partition metadata.
11-25 11:45:43.944   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.951   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.956   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] sspm_b is not in super partition metadata.
11-25 11:45:43.960   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.967   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.971   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] dtbo_b is not in super partition metadata.
11-25 11:45:43.975   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.981   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:43.987   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] tee_b is not in super partition metadata.
11-25 11:45:43.992   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.001   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.006   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] vbmeta_b is not in super partition metadata.
11-25 11:45:44.010   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.017   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.021   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] vbmeta_system_b is not in super partition metadata.
11-25 11:45:44.025   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.031   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.035   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] vbmeta_vendor_b is not in super partition metadata.
11-25 11:45:44.039   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.047   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.051   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(938)] wt_custom_b is not in super partition metadata.
11-25 11:45:44.055   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot A in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.061   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.068   839   839 I update_engine: Successfully unmapped snapshot hmd_custom_amx_b
11-25 11:45:44.100   839   839 I update_engine: [libfs_mgr]Created logical partition hmd_custom_amx_b-base on device /dev/block/dm-13
11-25 11:45:44.129   839   839 I update_engine: Mapped COW device for hmd_custom_amx_b at /dev/block/dm-14
11-25 11:45:44.167   839   839 I update_engine: Mapped hmd_custom_amx_b as snapshot device at /dev/block/dm-15
11-25 11:45:44.172   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(173)] Succesfully mapped hmd_custom_amx_b to device mapper (force_writable = 1); device path at /dev/block/dm-15
11-25 11:45:44.179   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.183   839   839 I update_engine: Successfully unmapped snapshot vendor_b
11-25 11:45:44.214   839   839 I update_engine: [libfs_mgr]Created logical partition vendor_b-base on device /dev/block/dm-16
11-25 11:45:44.249   839   839 I update_engine: Mapped COW device for vendor_b at /dev/block/dm-17
11-25 11:45:44.288   839   839 I update_engine: Mapped vendor_b as snapshot device at /dev/block/dm-18
11-25 11:45:44.293   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(173)] Succesfully mapped vendor_b to device mapper (force_writable = 1); device path at /dev/block/dm-18
11-25 11:45:44.300   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(319)] Loaded metadata from slot B in /dev/block/platform/bootdevice/by-name/super
11-25 11:45:44.305   839   839 I update_engine: Successfully unmapped snapshot product_b
11-25 11:45:44.334   839   839 I update_engine: [libfs_mgr]Created logical partition product_b-base on device /dev/block/dm-19
11-25 11:45:44.366   839   839 I update_engine: Mapped COW device for product_b at /dev/block/dm-20
11-25 11:45:44.407   839   839 I update_engine: Mapped product_b as snapshot device at /dev/block/dm-21
11-25 11:45:44.412   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(173)] Succesfully mapped product_b to device mapper (force_writable = 1); device path at /dev/block/dm-21
11-25 11:45:44.416   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new boot sha256: uKMzlkL7zm6hLQTQfgnWH71BKA/aPAepZ/Q+tRUvrDw= size: 33554432
11-25 11:45:44.419   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new system sha256: 2jGxzfOd4HRwOMOZCHRwQIbYBtILXeFYMP4eOFhXnNI= size: 1319157760
11-25 11:45:44.423   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new lk sha256: ZQazTsS9tPE88Ro/NUjMixwz3FvN6vpEkCFYvL4hxy8= size: 1011712
11-25 11:45:44.431   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new preloader sha256: K4qmWI9F4I+xoOKgCxswPrryXuJ9EvrDJ1/cB+rpjCU= size: 245760
11-25 11:45:44.437   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new logo sha256: CJF41cTBIcrUmGpYhPXcUVZJsScSbL2Vyqu4b+62e1g= size: 1130496
11-25 11:45:44.441   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new md1img sha256: 7j/07qwGI+IeC5AAFVlec8Xcxl31h30Xi4ghGvPsKz0= size: 49893376
11-25 11:45:44.446   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new spmfw sha256: 39SWcIvL99BJODXZa9ezSeFiRVOcOn9aaWwAKXTO9wg= size: 65536
11-25 11:45:44.451   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new scp sha256: MlExa3WI2Qy7c2JYVnr01ssKYNLcXu6hrlKXQuQWN2c= size: 589824
11-25 11:45:44.455   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new sspm sha256: 7XgNblQoxAC4PuUBThxGdidpj43bEOWcUXY4+8lx0t8= size: 438272
11-25 11:45:44.458   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new dtbo sha256: utmiqgav12P+fshVY/qv/n0PUlb+IXAl0NEQnD4eDeo= size: 53248
11-25 11:45:44.462   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new tee sha256: h1l1hfA+zMrwZXVuc1SnhYza2Pmque4zQqtuZFfROe8= size: 1118208
11-25 11:45:44.465   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new vbmeta sha256: uXjrsDM39QIJWtKUoPJgDy2UUKtaq6yQ6lWwk9lh3ys= size: 4096
11-25 11:45:44.468   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new vbmeta_system sha256: 6F1qP6ugnOQQg0UD+y/8prD2wdjDNLcujb+a7Johi44= size: 4096
11-25 11:45:44.472   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new vbmeta_vendor sha256: /sd70SKGE0DBA371EN56iQu04+ShX/T44ldfbWNHhIk= size: 4096
11-25 11:45:44.475   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new wt_custom sha256: hCWhjiu+R3kCz+YhWg4XPExmJCwT+vczBEsMBtPaf9o= size: 52428800
11-25 11:45:44.478   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new hmd_custom_amx sha256: +RRonoHa66GIDgLkS3a19ayH7rotq++NMWGtuufUrLg= size: 109522944
11-25 11:45:44.481   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new vendor sha256: V5cQebp4DJVGxSOMwkK5c1jxpOVbaTt8ebgIMSRiyVU= size: 274477056
11-25 11:45:44.485   839   839 I update_engine: [INFO:delta_performer.cc(451)] PartitionInfo new product sha256: gRS187yzxWVwQ5HqTZBJJ2Z+0gIIOg2NNHP1LOymCHE= size: 1281126400
11-25 11:45:44.488   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/boot_b partition without O_DSYNC
11-25 11:45:44.496   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:45:44.500   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 16 operations to partition "boot"
11-25 11:45:44.504   839   839 I update_engine: [INFO:delta_performer.cc(657)] Starting to apply update payload operations
11-25 11:45:45.139  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.01002)
11-25 11:45:45.663   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/dm-12 partition without O_DSYNC
11-25 11:45:45.672   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:45:45.677   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 630 operations to partition "system"
11-25 11:45:47.548  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.020027)
11-25 11:45:50.488  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.0300339)
11-25 11:45:53.786  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.0400409)
11-25 11:45:56.688  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.0500478)
11-25 11:45:59.392  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.0600547)
11-25 11:45:59.862  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.0700617)
11-25 11:46:00.321  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.0800686)
11-25 11:46:00.763  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.0900755)
11-25 11:46:02.621  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.100082)
11-25 11:46:05.236  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.110089)
11-25 11:46:05.551   839   839 I update_engine: [INFO:delta_performer.cc(209)] Completed 151/1501 operations (10%), 155156480/1408047432 bytes downloaded (11%), overall progress 10%
11-25 11:46:08.062  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.120096)
11-25 11:46:09.404  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.130103)
11-25 11:46:10.946  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.14011)
11-25 11:46:11.840  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.150117)
11-25 11:46:12.314  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.160124)
11-25 11:46:14.269  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.170131)
11-25 11:46:17.426  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.180138)
11-25 11:46:20.310  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.190145)
11-25 11:46:22.932  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.200152)
11-25 11:46:27.833  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.210159)
11-25 11:46:29.161  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.220166)
11-25 11:46:31.808  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.230173)
11-25 11:46:32.257   839   839 I update_engine: [INFO:delta_performer.cc(209)] Completed 271/1501 operations (18%), 326680576/1408047432 bytes downloaded (23%), overall progress 20%
11-25 11:46:34.429  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.24018)
11-25 11:46:37.095  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.250187)
11-25 11:46:39.852  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.260193)
11-25 11:46:42.909  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.2702)
11-25 11:46:45.035  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.280207)
11-25 11:46:46.581  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.290214)
11-25 11:46:49.263  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.300221)
11-25 11:46:51.950  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.310228)
11-25 11:46:55.046  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.320235)
11-25 11:46:58.101  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.330242)
11-25 11:46:58.989   839   839 I update_engine: [INFO:delta_performer.cc(209)] Completed 421/1501 operations (28%), 468877312/1408047432 bytes downloaded (33%), overall progress 30%
11-25 11:47:01.004  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.340249)
11-25 11:47:03.962  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.350256)
11-25 11:47:06.546  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.360263)
11-25 11:47:09.448  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.37027)
11-25 11:47:12.448  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.380277)
11-25 11:47:17.538  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.390284)
11-25 11:47:20.438  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.400291)
11-25 11:47:23.411  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.410297)
11-25 11:47:25.839   839   839 I update_engine: [INFO:delta_performer.cc(209)] Completed 580/1501 operations (38%), 591380480/1408047432 bytes downloaded (42%), overall progress 40%
11-25 11:47:25.864  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.420304)
11-25 11:47:30.218  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.430311)
11-25 11:47:32.109  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.440318)
11-25 11:47:32.923  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.450325)
11-25 11:47:33.973  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.460332)
11-25 11:47:34.564  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.470339)
11-25 11:47:34.892   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/lk_b partition without O_DSYNC
11-25 11:47:34.901   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:34.906   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 1 operations to partition "lk"
11-25 11:47:35.002   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/preloader_b partition without O_DSYNC
11-25 11:47:35.009   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:35.012   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 1 operations to partition "preloader"
11-25 11:47:35.044   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/logo_b partition without O_DSYNC
11-25 11:47:35.052   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:35.055   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 1 operations to partition "logo"
11-25 11:47:35.268   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/md1img_b partition without O_DSYNC
11-25 11:47:35.276   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:35.279   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 24 operations to partition "md1img"
11-25 11:47:36.980  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.480346)
11-25 11:47:37.989  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.490353)
11-25 11:47:38.356   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/spmfw_b partition without O_DSYNC
11-25 11:47:38.365   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:38.368   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 1 operations to partition "spmfw"
11-25 11:47:38.377   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/scp_b partition without O_DSYNC
11-25 11:47:38.383   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:38.387   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 1 operations to partition "scp"
11-25 11:47:38.433   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/sspm_b partition without O_DSYNC
11-25 11:47:38.440   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:38.444   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 1 operations to partition "sspm"
11-25 11:47:38.484   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/dtbo_b partition without O_DSYNC
11-25 11:47:38.492   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:38.496   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 1 operations to partition "dtbo"
11-25 11:47:38.507   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/tee_b partition without O_DSYNC
11-25 11:47:38.513   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:38.517   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 1 operations to partition "tee"
11-25 11:47:38.627   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/vbmeta_b partition without O_DSYNC
11-25 11:47:38.635   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:38.638   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 1 operations to partition "vbmeta"
11-25 11:47:38.645   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/vbmeta_system_b partition without O_DSYNC
11-25 11:47:38.652   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:38.655   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 1 operations to partition "vbmeta_system"
11-25 11:47:38.661   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/vbmeta_vendor_b partition without O_DSYNC
11-25 11:47:38.668   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:38.671   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 1 operations to partition "vbmeta_vendor"
11-25 11:47:38.678   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/platform/bootdevice/by-name/wt_custom_b partition without O_DSYNC
11-25 11:47:38.684   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:38.687   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 25 operations to partition "wt_custom"
11-25 11:47:39.345   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/dm-15 partition without O_DSYNC
11-25 11:47:39.355   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:39.358   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 53 operations to partition "hmd_custom_amx"
11-25 11:47:41.032   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/dm-18 partition without O_DSYNC
11-25 11:47:41.041   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:47:41.046   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 131 operations to partition "vendor"
11-25 11:47:42.085   839   839 I update_engine: [INFO:delta_performer.cc(209)] Completed 766/1501 operations (51%), 704036864/1408047432 bytes downloaded (50%), overall progress 50%
11-25 11:47:42.110  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.50036)
11-25 11:47:45.718  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.510367)
11-25 11:47:48.463  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.520374)
11-25 11:47:51.779  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.530381)
11-25 11:47:54.958  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.540388)
11-25 11:47:58.030  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.550395)
11-25 11:48:00.254  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.560401)
11-25 11:48:02.377   839   839 I update_engine: [INFO:delta_performer.cc(385)] Opening /dev/block/dm-21 partition without O_DSYNC
11-25 11:48:02.386   839   839 I update_engine: [INFO:delta_performer.cc(128)] Caching writes.
11-25 11:48:02.389   839   839 I update_engine: [INFO:delta_performer.cc(397)] Applying 611 operations to partition "product"
11-25 11:48:02.395  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.570408)
11-25 11:48:04.390  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.580415)
11-25 11:48:06.712  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.590422)
11-25 11:48:09.391   839   839 I update_engine: [INFO:delta_performer.cc(209)] Completed 931/1501 operations (62%), 841482240/1408047432 bytes downloaded (59%), overall progress 60%
11-25 11:48:09.832  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.600429)
11-25 11:48:12.246  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.610436)
11-25 11:48:14.475  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.620443)
11-25 11:48:16.602  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.63045)
11-25 11:48:18.138  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.640457)
11-25 11:48:18.612  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.650464)
11-25 11:48:20.099  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.660471)
11-25 11:48:21.698  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.670478)
11-25 11:48:23.170  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.680485)
11-25 11:48:25.128  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.690492)
11-25 11:48:26.543  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.700499)
11-25 11:48:28.241  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.710506)
11-25 11:48:29.375   839   839 I update_engine: [INFO:delta_performer.cc(209)] Completed 1051/1501 operations (70%), 1007616000/1408047432 bytes downloaded (71%), overall progress 70%
11-25 11:48:30.291  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.720512)
11-25 11:48:32.988  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.730519)
11-25 11:48:34.373  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.740526)
11-25 11:48:35.415  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.750533)
11-25 11:48:38.458  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.76054)
11-25 11:48:41.235  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.770547)
11-25 11:48:42.977  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.780554)
11-25 11:48:45.307  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.790561)
11-25 11:48:47.214  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.800568)
11-25 11:48:52.002  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.810575)
11-25 11:48:54.677   839   839 I update_engine: [INFO:delta_performer.cc(209)] Completed 1199/1501 operations (79%), 1154613248/1408047432 bytes downloaded (82%), overall progress 80%
11-25 11:48:54.918  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.820582)
11-25 11:48:57.860  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.830589)
11-25 11:48:59.614  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.840596)
11-25 11:49:01.936  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.850603)
11-25 11:49:05.376  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.86061)
11-25 11:49:08.417  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.870616)
11-25 11:49:11.350  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.880623)
11-25 11:49:14.625  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.89063)
11-25 11:49:16.335  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.900637)
11-25 11:49:19.237  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.910644)
11-25 11:49:20.582   839   839 I update_engine: [INFO:delta_performer.cc(209)] Completed 1351/1501 operations (90%), 1288437760/1408047432 bytes downloaded (91%), overall progress 90%
11-25 11:49:21.897  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.920651)
11-25 11:49:24.824  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.930658)
11-25 11:49:27.603  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.940665)
11-25 11:49:34.020  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.950672)
11-25 11:49:37.344  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.960679)
11-25 11:49:40.287  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.970686)
11-25 11:49:43.173  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.980693)
11-25 11:49:45.747  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_DOWNLOADING (3), 0.9907)
11-25 11:49:46.226   839   839 I update_engine: [INFO:delta_performer.cc(209)] Completed 1501/1501 operations (100%), 1408047432/1408047432 bytes downloaded (100%), overall progress 100%
11-25 11:49:46.232   839   839 I update_engine: [INFO:delta_performer.cc(1665)] Extracted signature data of size 267 at 1407960856
11-25 11:49:46.235   839   839 I update_engine: [INFO:multi_range_http_fetcher.cc(115)] Terminating transfer.
11-25 11:49:46.238   839   839 I update_engine: [INFO:multi_range_http_fetcher.cc(177)] Received transfer terminated.
11-25 11:49:46.241   839   839 I update_engine: [INFO:multi_range_http_fetcher.cc(129)] TransferEnded w/ code 200
11-25 11:49:46.244   839   839 I update_engine: [INFO:multi_range_http_fetcher.cc(163)] Done w/ all transfers
11-25 11:49:46.390   839   839 I update_engine: [INFO:delta_performer.cc(1693)] Verifying using certificates: /system/etc/security/otacerts.zip
11-25 11:49:46.399   839   839 I update_engine: [INFO:payload_verifier.cc(102)] signature blob size = 267
11-25 11:49:46.404   839   839 I update_engine: [INFO:payload_verifier.cc(118)] Truncating the signature to its unpadded size: 256.
11-25 11:49:46.407   839   839 I update_engine: [INFO:payload_verifier.cc(129)] Verified correct signature 1 out of 1 signatures.
11-25 11:49:46.410   839   839 I update_engine: [INFO:delta_performer.cc(1915)] Payload hash matches value in payload.
11-25 11:49:46.413   839   839 I update_engine: [INFO:download_action.cc(400)] Collections of histograms for UpdateEngine.DownloadAction.
11-25 11:49:46.413   839   839 I update_engine: Histogram: UpdateEngine.DownloadAction.InstallOperation::REPLACE.Duration recorded 1501 samples, mean = 136.2
11-25 11:49:46.413   839   839 I update_engine: 0    -O                                                                        (7 = 0.5%)
11-25 11:49:46.413   839   839 I update_engine: 10   --------------O                                                           (149 = 9.9%) {0.5%}
11-25 11:49:46.413   839   839 I update_engine: 18   ---O                                                                      (34 = 2.3%) {10.4%}
11-25 11:49:46.413   839   839 I update_engine: 32   -----O                                                                    (51 = 3.4%) {12.7%}
11-25 11:49:46.413   839   839 I update_engine: 57   ----------------------O                                                   (229 = 15.3%) {16.1%}
11-25 11:49:46.413   839   839 I update_engine: 101  ------------------------------------------------------------------------O (752 = 50.1%) {31.3%}
11-25 11:49:46.413   839   839 I update_engine: 179  -----------------------O                                                  (240 = 16.0%) {81.4%}
11-25 11:49:46.413   839   839 I update_engine: 317  --O                                                                       (25 = 1.7%) {97.4%}
11-25 11:49:46.413   839   839 I update_engine: 561  -O                                                                        (14 = 0.9%) {99.1%}
11-25 11:49:46.413   839   839 I update_engine: 993  ... 
11-25 11:49:46.413   839   839 I update_engine: 
11-25 11:49:46.417  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0)
11-25 11:49:46.424   839   839 I update_engine: [INFO:action_processor.cc(116)] ActionProcessor: finished DownloadAction with code ErrorCode::kSuccess
11-25 11:49:46.429   839   839 I update_engine: [INFO:action_processor.cc(143)] ActionProcessor: starting FilesystemVerifierAction
11-25 11:49:46.432   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 0 (boot) on device /dev/block/platform/bootdevice/by-name/boot_b
11-25 11:49:46.602   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of boot: uKMzlkL7zm6hLQTQfgnWH71BKA/aPAepZ/Q+tRUvrDw=
11-25 11:49:46.619   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 1 (system) on device /dev/block/dm-12
11-25 11:49:57.141   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of system: 2jGxzfOd4HRwOMOZCHRwQIbYBtILXeFYMP4eOFhXnNI=
11-25 11:49:57.235   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 2 (lk) on device /dev/block/platform/bootdevice/by-name/lk_b
11-25 11:49:57.248   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of lk: ZQazTsS9tPE88Ro/NUjMixwz3FvN6vpEkCFYvL4hxy8=
11-25 11:49:57.252   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 3 (preloader) on device /dev/block/platform/bootdevice/by-name/preloader_b
11-25 11:49:57.258   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of preloader: K4qmWI9F4I+xoOKgCxswPrryXuJ9EvrDJ1/cB+rpjCU=
11-25 11:49:57.261   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 4 (logo) on device /dev/block/platform/bootdevice/by-name/logo_b
11-25 11:49:57.275   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of logo: CJF41cTBIcrUmGpYhPXcUVZJsScSbL2Vyqu4b+62e1g=
11-25 11:49:57.281   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 5 (md1img) on device /dev/block/platform/bootdevice/by-name/md1img_b
11-25 11:49:57.529   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of md1img: 7j/07qwGI+IeC5AAFVlec8Xcxl31h30Xi4ghGvPsKz0=
11-25 11:49:57.549   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 6 (spmfw) on device /dev/block/platform/bootdevice/by-name/spmfw_b
11-25 11:49:57.556   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of spmfw: 39SWcIvL99BJODXZa9ezSeFiRVOcOn9aaWwAKXTO9wg=
11-25 11:49:57.560   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 7 (scp) on device /dev/block/platform/bootdevice/by-name/scp_b
11-25 11:49:57.570   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of scp: MlExa3WI2Qy7c2JYVnr01ssKYNLcXu6hrlKXQuQWN2c=
11-25 11:49:57.575   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 8 (sspm) on device /dev/block/platform/bootdevice/by-name/sspm_b
11-25 11:49:57.585   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of sspm: 7XgNblQoxAC4PuUBThxGdidpj43bEOWcUXY4+8lx0t8=
11-25 11:49:57.589   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 9 (dtbo) on device /dev/block/platform/bootdevice/by-name/dtbo_b
11-25 11:49:57.595   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of dtbo: utmiqgav12P+fshVY/qv/n0PUlb+IXAl0NEQnD4eDeo=
11-25 11:49:57.598   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 10 (tee) on device /dev/block/platform/bootdevice/by-name/tee_b
11-25 11:49:57.613   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of tee: h1l1hfA+zMrwZXVuc1SnhYza2Pmque4zQqtuZFfROe8=
11-25 11:49:57.618   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 11 (vbmeta) on device /dev/block/platform/bootdevice/by-name/vbmeta_b
11-25 11:49:57.623   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta: uXjrsDM39QIJWtKUoPJgDy2UUKtaq6yQ6lWwk9lh3ys=
11-25 11:49:57.627   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 12 (vbmeta_system) on device /dev/block/platform/bootdevice/by-name/vbmeta_system_b
11-25 11:49:57.632   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta_system: 6F1qP6ugnOQQg0UD+y/8prD2wdjDNLcujb+a7Johi44=
11-25 11:49:57.636   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 13 (vbmeta_vendor) on device /dev/block/platform/bootdevice/by-name/vbmeta_vendor_b
11-25 11:49:57.641   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of vbmeta_vendor: /sd70SKGE0DBA371EN56iQu04+ShX/T44ldfbWNHhIk=
11-25 11:49:57.646   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 14 (wt_custom) on device /dev/block/platform/bootdevice/by-name/wt_custom_b
11-25 11:49:57.898   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of wt_custom: hCWhjiu+R3kCz+YhWg4XPExmJCwT+vczBEsMBtPaf9o=
11-25 11:49:57.917   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 15 (hmd_custom_amx) on device /dev/block/dm-15
11-25 11:49:58.803   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of hmd_custom_amx: +RRonoHa66GIDgLkS3a19ayH7rotq++NMWGtuufUrLg=
11-25 11:49:58.838   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 16 (vendor) on device /dev/block/dm-18
11-25 11:50:00.939   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of vendor: V5cQebp4DJVGxSOMwkK5c1jxpOVbaTt8ebgIMSRiyVU=
11-25 11:50:00.990   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(117)] Hashing partition 17 (product) on device /dev/block/dm-21
11-25 11:50:10.562   839   839 I update_engine: [INFO:filesystem_verifier_action.cc(237)] Hash of product: gRS187yzxWVwQ5HqTZBJJ2Z+0gIIOg2NNHP1LOymCHE=
11-25 11:50:10.655   839   839 I update_engine: [INFO:action_processor.cc(116)] ActionProcessor: finished FilesystemVerifierAction with code ErrorCode::kSuccess
11-25 11:50:10.660   839   839 I update_engine: [INFO:action_processor.cc(143)] ActionProcessor: starting PostinstallRunnerAction
11-25 11:50:10.663  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0)
11-25 11:50:10.668   839   839 I update_engine: [INFO:postinstall_runner_action.cc(174)] 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
11-25 11:50:10.677   839   839 I update_engine: [INFO:postinstall_runner_action.cc(181)] Format file for new system/bin/otapreopt_script is: data
11-25 11:50:15.970  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.01)
11-25 11:50:25.535  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.02)
11-25 11:50:35.553  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.03)
11-25 11:50:40.605  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.04)
11-25 11:50:50.133  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.05)
11-25 11:51:05.155  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.07)
11-25 11:51:20.074  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.08)
11-25 11:51:29.841  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.1)
11-25 11:51:44.371  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.12)
11-25 11:51:54.092  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.13)
11-25 11:52:04.115  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.14)
11-25 11:52:14.165  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.15)
11-25 11:52:19.316  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.16)
11-25 11:52:28.910  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.17)
11-25 11:52:43.667  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.19)
11-25 11:52:53.603  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.2)
11-25 11:53:08.187  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.22)
11-25 11:53:18.044  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.23)
11-25 11:53:32.751  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.25)
11-25 11:53:42.534  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.26)
11-25 11:53:47.307  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.27)
11-25 11:53:56.975  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.28)
11-25 11:54:11.706  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.3)
11-25 11:54:21.499  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.31)
11-25 11:54:26.414  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.32)
11-25 11:54:36.288  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.33)
11-25 11:54:45.996  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.34)
11-25 11:55:00.501  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.36)
11-25 11:55:10.144  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.37)
11-25 11:55:14.955  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.38)
11-25 11:55:24.646  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.39)
11-25 11:55:34.312  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.4)
11-25 11:55:48.533  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.42)
11-25 11:55:58.152  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.43)
11-25 11:56:02.974  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.44)
11-25 11:56:12.603  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.45)
11-25 11:56:22.008  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.46)
11-25 11:56:36.712  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.48)
11-25 11:56:46.414  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.49)
11-25 11:56:51.453  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.5)
11-25 11:57:01.182  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.51)
11-25 11:57:05.927  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.52)
11-25 11:57:15.612  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.53)
11-25 11:57:25.565  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.54)
11-25 11:57:30.483  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.55)
11-25 11:57:40.457  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.56)
11-25 11:57:55.343  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.58)
11-25 11:58:05.212  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.59)
11-25 11:58:15.203  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.6)
11-25 11:58:19.971  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.61)
11-25 11:58:29.931  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.62)
11-25 11:58:39.861  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.63)
11-25 11:58:44.681  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.64)
11-25 11:58:54.255  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.65)
11-25 11:59:04.240  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.66)
11-25 11:59:08.937  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.67)
11-25 11:59:18.863  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.68)
11-25 11:59:33.530  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.7)
11-25 11:59:43.406  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.71)
11-25 11:59:48.341  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.72)
11-25 11:59:57.975  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.73)
11-25 12:00:07.663  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.74)
11-25 12:00:12.473  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.75)
11-25 12:00:22.321  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.76)
11-25 12:00:32.203  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.77)
11-25 12:00:37.164  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.78)
11-25 12:00:47.006  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.79)
11-25 12:00:56.822  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.8)
11-25 12:01:01.670  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.81)
11-25 12:01:16.275  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.82)
11-25 12:01:21.234  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.83)
11-25 12:01:26.257  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.84)
11-25 12:01:35.946  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.85)
11-25 12:01:45.759  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.86)
11-25 12:01:50.440  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.87)
11-25 12:02:00.075  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.88)
11-25 12:02:09.601  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.89)
11-25 12:02:14.458  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.9)
11-25 12:02:23.956  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.91)
11-25 12:02:28.914  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.92)
11-25 12:02:38.767  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.93)
11-25 12:02:53.395  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.95)
11-25 12:03:03.225  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.96)
11-25 12:03:13.131  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.97)
11-25 12:03:18.003  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.98)
11-25 12:03:27.383  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 0.99)
11-25 12:03:36.839  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 1)
11-25 12:03:36.902  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 1)
11-25 12:03:36.940   839   839 I update_engine: [INFO:subprocess.cc(157)] Subprocess output:
11-25 12:03:36.940   839   839 I update_engine: Complete or error.
11-25 12:03:36.959  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_FINALIZING (5), 1)
11-25 12:03:36.961   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(863)] Snapshot writes are done.
11-25 12:03:36.967   839   839 I update_engine: Wipe is not scheduled. Deleting forward merge indicator.
11-25 12:03:36.992   839   839 I update_engine: [INFO:postinstall_runner_action.cc(371)] All post-install commands succeeded
11-25 12:03:36.998   839   839 I update_engine: [INFO:action_processor.cc(116)] ActionProcessor: finished last action PostinstallRunnerAction with code ErrorCode::kSuccess
11-25 12:03:37.001   839   839 I update_engine: [INFO:update_attempter_android.cc(522)] Processing Done.
11-25 12:03:37.006   839   839 I update_engine: [INFO:update_attempter_android.cc(535)] Update successfully applied, waiting to reboot.
11-25 12:03:37.010   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(263)] Destroying [hmd_custom_amx_b, product_b, system_b, vendor_b] from device mapper
11-25 12:03:37.050   839   839 I update_engine: [libfs_mgr]Unmapped logical partition hmd_custom_amx_b
11-25 12:03:37.140   839   839 I update_engine: Successfully unmapped snapshot hmd_custom_amx_b
11-25 12:03:37.145   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(249)] Successfully unmapped hmd_custom_amx_b from device mapper.
11-25 12:03:37.180   839   839 I update_engine: [libfs_mgr]Unmapped logical partition product_b
11-25 12:03:37.330   839   839 I update_engine: Successfully unmapped snapshot product_b
11-25 12:03:37.347   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(249)] Successfully unmapped product_b from device mapper.
11-25 12:03:37.410   839   839 I update_engine: [libfs_mgr]Unmapped logical partition system_b
11-25 12:03:37.520   839   839 I update_engine: Successfully unmapped snapshot system_b
11-25 12:03:37.525   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(249)] Successfully unmapped system_b from device mapper.
11-25 12:03:37.604   839   839 I update_engine: [libfs_mgr]Unmapped logical partition vendor_b
11-25 12:03:37.700   839   839 I update_engine: Successfully unmapped snapshot vendor_b
11-25 12:03:37.710   839   839 I update_engine: [INFO:dynamic_partition_control_android.cc(249)] Successfully unmapped vendor_b from device mapper.
11-25 12:03:37.715  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(92)] onStatusUpdate(UPDATE_STATUS_UPDATED_NEED_REBOOT (6), 0)
11-25 12:03:37.717  7013  7013 I update_engine_client: [INFO:update_engine_client_android.cc(100)] onPayloadApplicationComplete(ErrorCode::kSuccess (0))
11-25 12:03:37.722   839   839 I update_engine: [INFO:metrics_reporter_android.cc(131)] Current update attempt downloads 1342 bytes data
11-25 12:03:37.732   839   839 I update_engine: [INFO:metrics_utils.cc(380)] Updated Marker = 1/1/1970 0:29:10 GMT