开机日志分析积累

最新推荐文章于 2023-08-24 11:21:12 发布
最新推荐文章于 2023-08-24 11:21:12 发布
阅读量591 收藏
点赞数
分类专栏： 性能优化 文章标签： framework 开机日志 性能优化
版权声明：本文为博主原创文章，遵循 CC 4.0 BY-SA 版权协议，转载请附上原文出处链接和本声明。
本文链接：https://blog.csdn.net/qq_40731414/article/details/130656131
版权

开机日志分析积累

hongxi.zhu 2022-11-20

1. 开机各个阶段的日志和描述

logcat -b all 抓取所有类型开机日志，从下面的阶段event log可以了解每个阶段的耗时,方便我们定位开机性能的问题。
例子：

	Line    62: 11-10 23:03:54.396   792   792 I boot_progress_start: 3632
	Line    71: 11-10 23:03:54.953   792   792 I boot_progress_preload_start: 4189
	Line    88: 11-10 23:03:56.254   792   792 I boot_progress_preload_end: 5489
	Line    92: 11-10 23:03:56.642  1367  1367 I boot_progress_system_run: 5878
	Line   559: 11-10 23:03:57.485  1367  1367 I boot_progress_pms_start: 6721
	Line   872: 11-10 23:03:57.663  1367  1367 I boot_progress_pms_system_scan_start: 6898
	Line   894: 11-10 23:03:57.938  1367  1367 I boot_progress_pms_data_scan_start: 7174
	Line   900: 11-10 23:03:57.953  1367  1367 I boot_progress_pms_scan_end: 7189
	Line   929: 11-10 23:03:58.139  1367  1367 I boot_progress_pms_ready: 7375
	Line  2021: 11-10 23:03:59.639  1367  1367 I boot_progress_ams_ready: 8875
	Line  3361: 11-10 23:04:01.016  1367  1533 I boot_progress_enable_screen: 10251
	Line  5251: 11-10 23:04:03.237   644   675 I sf_stop_bootanim: 12473
	Line  5252: 11-10 23:04:03.238  1367  1533 I wm_boot_animation_done: 12473
log tagdesc
boot_progress_start系统进入用户空间，标志着kernel启动完成
boot_progress_preload_startZygote启动
boot_progress_preload_endZygote结束
boot_progress_system_runSystemServer ready,开始启动Android系统服务
boot_progress_pms_startPMS开始扫描安装的应用
boot_progress_pms_system_scan_startPMS先行扫描/system目录下的安装包
boot_progress_pms_data_scan_startPMS扫描/data目录下的安装包
boot_progress_pms_scan_endPMS扫描结束
boot_progress_pms_readyPMS就绪
boot_progress_ams_readyAMS就绪
boot_progress_enable_screenAMS启动完成后开始激活屏幕，从此以后屏幕才能响应用户的触摸，它在WindowManagerService发出退出开机动画的时间节点之前
sf_stop_bootanimSF设置service.bootanim.exit属性值为1，标志系统要结束开机动画了
wm_boot_animation_done开机动画结束，这一步用户能直观感受到开机结束

从Android 12源码中找到上述的节点log位置：

  1. boot_progress_start
# frameworks/base/core/jni/AndroidRuntime.cpp:1208

void AndroidRuntime::start(const char* className, const Vector<String8>& options, bool zygote)
{
    ALOGD(">>>>>> START %s uid %d <<<<<<\n",
            className != NULL ? className : "(unknown)", getuid());

    static const String8 startSystemServer("start-system-server");
    // Whether this is the primary zygote, meaning the zygote which will fork system server.
    bool primary_zygote = false;

    /*
     * 'startSystemServer == true' means runtime is obsolete and not run from
     * init.rc anymore, so we print out the boot start event here.
     */
    for (size_t i = 0; i < options.size(); ++i) {
        if (options[i] == startSystemServer) {
            primary_zygote = true;
           /* track our progress through the boot sequence */
           const int LOG_BOOT_PROGRESS_START = 3000;
           LOG_EVENT_LONG(LOG_BOOT_PROGRESS_START,  ns2ms(systemTime(SYSTEM_TIME_MONOTONIC)));  //boot_progress_start
        }
    }
    ...
  1. boot_progress_preload_start
frameworks/base/core/java/com/android/internal/os/ZygoteInit.java:954

   public static void main(String[] argv) {
            ...
            // In some configurations, we avoid preloading resources and classes eagerly.
            // In such cases, we will preload things prior to our first fork.
            if (!enableLazyPreload) {
                bootTimingsTraceLog.traceBegin("ZygotePreload");
                EventLog.writeEvent(LOG_BOOT_PROGRESS_PRELOAD_START,
                        SystemClock.uptimeMillis());  //boot_progress_preload_start
                preload(bootTimingsTraceLog);
                EventLog.writeEvent(LOG_BOOT_PROGRESS_PRELOAD_END,
                        SystemClock.uptimeMillis());
                bootTimingsTraceLog.traceEnd(); // ZygotePreload
            }
            ...
  1. boot_progress_preload_end
frameworks/base/core/java/com/android/internal/os/ZygoteInit.java:957

   public static void main(String[] argv) {
            ...
            // In some configurations, we avoid preloading resources and classes eagerly.
            // In such cases, we will preload things prior to our first fork.
            if (!enableLazyPreload) {
                bootTimingsTraceLog.traceBegin("ZygotePreload");
                EventLog.writeEvent(LOG_BOOT_PROGRESS_PRELOAD_START,
                        SystemClock.uptimeMillis());
                preload(bootTimingsTraceLog);
                EventLog.writeEvent(LOG_BOOT_PROGRESS_PRELOAD_END,
                        SystemClock.uptimeMillis());  //boot_progress_preload_end
                bootTimingsTraceLog.traceEnd(); // ZygotePreload
            }
            ...
  1. boot_progress_system_run
frameworks/base/services/java/com/android/server/SystemServer.java:759

        private void run() {
        TimingsTraceAndSlog t = new TimingsTraceAndSlog();
        try {
            t.traceBegin("InitBeforeStartServices");
            ...
            // Here we go!
            Slog.i(TAG, "Entered the Android system server!");
            final long uptimeMillis = SystemClock.elapsedRealtime();
            EventLog.writeEvent(EventLogTags.BOOT_PROGRESS_SYSTEM_RUN, uptimeMillis);  //boot_progress_system_run
            if (!mRuntimeRestart) {
                FrameworkStatsLog.write(FrameworkStatsLog.BOOT_TIME_EVENT_ELAPSED_TIME_REPORTED,
                        FrameworkStatsLog
                                .BOOT_TIME_EVENT_ELAPSED_TIME__EVENT__SYSTEM_SERVER_INIT_START,
                        uptimeMillis);
            }
            ...
  1. boot_progress_pms_start
# frameworks/base/services/core/java/com/android/server/pm/PackageManagerService.java:7386

    public PackageManagerService(Injector injector, boolean onlyCore, boolean factoryTest,
            final String buildFingerprint, final boolean isEngBuild,
            final boolean isUserDebugBuild, final int sdkVersion, final String incrementalVersion) {
        mIsEngBuild = isEngBuild;
        mIsUserDebugBuild = isUserDebugBuild;
        mSdkVersion = sdkVersion;
        mIncrementalVersion = incrementalVersion;
        mInjector = injector;
        mInjector.getSystemWrapper().disablePackageCaches();

        final TimingsTraceAndSlog t = new TimingsTraceAndSlog(TAG + "Timing",
                Trace.TRACE_TAG_PACKAGE_MANAGER);
        mPendingBroadcasts = new PendingPackageBroadcasts();

        mInjector.bootstrap(this);
        mLock = injector.getLock();
        mInstallLock = injector.getInstallLock();
        LockGuard.installLock(mLock, LockGuard.INDEX_PACKAGES);
        EventLog.writeEvent(EventLogTags.BOOT_PROGRESS_PMS_START,
                SystemClock.uptimeMillis());  //boot_progress_pms_start
        ...
  1. boot_progress_pms_system_scan_start
# frameworks/base/services/core/java/com/android/server/pm/PackageManagerService.java:7587
    public PackageManagerService(Injector injector, boolean onlyCore, boolean factoryTest,
            final String buildFingerprint, final boolean isEngBuild,
            final boolean isUserDebugBuild, final int sdkVersion, final String incrementalVersion) {
            ...
            EventLog.writeEvent(EventLogTags.BOOT_PROGRESS_PMS_SYSTEM_SCAN_START,
                    startTime);  //boot_progress_pms_system_scan_start
            ...
            for (int i = mDirsToScanAsSystem.size() - 1; i >= 0; i--) {
                final ScanPartition partition = mDirsToScanAsSystem.get(i);
                if (partition.getOverlayFolder() == null) {
                    continue;
                }
                scanDirTracedLI(partition.getOverlayFolder(), systemParseFlags,
                        systemScanFlags | partition.scanFlag, 0,
                        packageParser, executorService);
            }
            ...
  1. boot_progress_pms_data_scan_start
# frameworks/base/services/core/java/com/android/server/pm/PackageManagerService.java:7790
            ...
            final long systemScanTime = SystemClock.uptimeMillis() - startTime;
            final int systemPackagesCount = mPackages.size();
            Slog.i(TAG, "Finished scanning system apps. Time: " + systemScanTime
                    + " ms, packageCount: " + systemPackagesCount
                    + " , timePerPackage: "
                    + (systemPackagesCount == 0 ? 0 : systemScanTime / systemPackagesCount)
                    + " , cached: " + cachedSystemApps);
            if (mIsUpgrade && systemPackagesCount > 0) {
                //CHECKSTYLE:OFF IndentationCheck
                FrameworkStatsLog.write(FrameworkStatsLog.BOOT_TIME_EVENT_DURATION_REPORTED,
                    BOOT_TIME_EVENT_DURATION__EVENT__OTA_PACKAGE_MANAGER_SYSTEM_APP_AVG_SCAN_TIME,
                    systemScanTime / systemPackagesCount);
                //CHECKSTYLE:ON IndentationCheck
            }
            if (!mOnlyCore) {
                EventLog.writeEvent(EventLogTags.BOOT_PROGRESS_PMS_DATA_SCAN_START,
                        SystemClock.uptimeMillis());  // boot_progress_pms_data_scan_start
                scanDirTracedLI(mAppInstallDir, 0, scanFlags | SCAN_REQUIRE_KNOWN, 0,
                        packageParser, executorService);

            }
            ...
  1. boot_progress_pms_scan_end
# frameworks/base/services/core/java/com/android/server/pm/PackageManagerService.java:7982

            ...
            // Now that we know all the packages we are keeping,
            // read and update their last usage times.
            mPackageUsage.read(packageSettings);
            mCompilerStats.read();

            EventLog.writeEvent(EventLogTags.BOOT_PROGRESS_PMS_SCAN_END,
                    SystemClock.uptimeMillis()); //boot_progress_pms_scan_end
            Slog.i(TAG, "Time to scan packages: "
                    + ((SystemClock.uptimeMillis()-startTime)/1000f)
                    + " seconds");
            ...
  1. boot_progress_pms_ready
# frameworks/base/services/core/java/com/android/server/pm/PackageManagerService.java:8121

            ...
            // can downgrade to reader
            t.traceBegin("write settings");
            writeSettingsLPrTEMP();
            t.traceEnd();
            EventLog.writeEvent(EventLogTags.BOOT_PROGRESS_PMS_READY,
                    SystemClock.uptimeMillis());  //boot_progress_pms_ready
            ...
  1. boot_progress_ams_ready
# frameworksbase/services/core/java/com/android/server/am/ActivityManagerService.java:7612

    /**
     * Ready. Set. Go!
     */
    public void systemReady(final Runnable goingCallback, @NonNull TimingsTraceAndSlog t) {
        t.traceBegin("PhaseActivityManagerReady");
        mSystemServiceManager.preSystemReady();
        ...
        Slog.i(TAG, "System now ready");

        EventLogTags.writeBootProgressAmsReady(SystemClock.uptimeMillis());  //boot_progress_ams_ready
        ...
  1. boot_progress_enable_screen
# framework/base/services/core/java/com/android/server/wm/ActivityTaskManagerService.java:5620

        ...
        @Override
        public void enableScreenAfterBoot(boolean booted) {
            writeBootProgressEnableScreen(SystemClock.uptimeMillis());  //boot_progress_enable_screen
            mWindowManager.enableScreenAfterBoot();
            synchronized (mGlobalLock) {
                updateEventDispatchingLocked(booted);
            }
        }
  1. sf_stop_bootanim
# framework/native/services/surfaceflinger/SurfaceFlinger.cpp:750

void SurfaceFlinger::bootFinished() {
    ...
    // stop boot animation
    // formerly we would just kill the process, but we now ask it to exit so it
    // can choose where to stop the animation.
    property_set("service.bootanim.exit", "1");

    const int LOGTAG_SF_STOP_BOOTANIM = 60110;
    LOG_EVENT_LONG(LOGTAG_SF_STOP_BOOTANIM,
                   ns2ms(systemTime(SYSTEM_TIME_MONOTONIC)));  //sf_stop_bootanim
    ...
  1. wm_boot_animation_done
# framework/base/services/core/java/com/android/server/wm/WindowManagerService.java:3691

    private void performEnableScreen() {
            ...
            try {
                IBinder surfaceFlinger = ServiceManager.getService("SurfaceFlinger");
                if (surfaceFlinger != null) {
                    ProtoLog.i(WM_ERROR, "******* TELLING SURFACE FLINGER WE ARE BOOTED!");
                    Parcel data = Parcel.obtain();
                    data.writeInterfaceToken("android.ui.ISurfaceComposer");
                    surfaceFlinger.transact(IBinder.FIRST_CALL_TRANSACTION, // BOOT_FINISHED
                            data, null, 0);
                    data.recycle();
                }
            } catch (RemoteException ex) {
                ProtoLog.e(WM_ERROR, "Boot completed: SurfaceFlinger is dead!");
            }

            EventLogTags.writeWmBootAnimationDone(SystemClock.uptimeMillis());  //wm_boot_animation_done
            Trace.asyncTraceEnd(TRACE_TAG_WINDOW_MANAGER, "Stop bootanim", 0);
            mDisplayEnabled = true;
            ProtoLog.i(WM_DEBUG_SCREEN_ON, "******************** ENABLING SCREEN!");

            // Enable input dispatch.
            mInputManagerCallback.setEventDispatchingLw(mEventDispatchingEnabled);
        }

        try {
            mActivityManager.bootAnimationComplete();
        } catch (RemoteException e) {
        }

        mPolicy.enableScreenAfterBoot();

        // Make sure the last requested orientation has been applied.
        updateRotationUnchecked(false, false);
    }
坂田民工
关注
  • 0
    点赞
  • 踩
  • 0
    收藏
    觉得还不错? 一键收藏
  • 打赏
    打赏
  • 0
    评论
专栏目录
读取系统日志获取开关机时间
11-16
通过读取windows日志来获取系统的开关机时间，代码简单易懂，可自行扩展。
Android 开机日志
xiaowang_lj的博客
05-23 1163
这里卖关子了：日志6要打印出来，是Home应用进程进如空闲后，会出发请求触发打印出日志6.也就是说，因为Home进程一直忙，所以拖延了将近5S的开机时间。比如说第5步日志,他表示的意思是Home的启动所话费的时间，但是实际上也表达另外一个意思，那就是现在Home中启动的Activity(也就是桌面)，对应在WMS中的WindowState是一个准备显示的状态(Readytoshow).那么就可以这样理解，如果现在关闭开机动画，就能看到Home了。为了方便排版，做了一个截图，放大看就好了。
【查看Windows 系统开关机日志】
wj138753的博客
08-07 1万+
Windows系统通开关机日志查询
开机启动日志 linux,linux笔记之 开机服务启动的控制，系统日志的查看，防火墙的关闭...
weixin_39627361的博客
05-01 368
/etc/inittab 配置默认运行级别/etc/sysconfig/init 控制tty终端开启数量 终端颜色方案/etc/init/rcS.conf 加载rc.sysinit脚本，完成初始化任务/etc/rc.d/rc.local 可以更改该文件，添加开机默认启动的命令命令：/etc/init.d/服务名称 控制类型该目录下存放着各种服务例如启动httpd,/etc/init.d/httpd...
Window Server 与 Windows 系统开关机日志查看方法
一只奋斗的猪
08-24 9715
环境：Windows Server 2019（也适用于Windows其他系统）。不同版本的 Windows 图标可能有所不同，但是服务器级 Windows Server 与普通桌面级 Windows 还会有些操作上的差别，但是最终的日志是一样的。
小米开机动画
02-01
使用Logcat工具进行日志分析，排查可能出现的问题，如加载延迟、动画卡顿等。 7. **用户体验**：好的开机动画应考虑加载速度和电池消耗。优化动画文件大小，减少内存占用，同时保持视觉效果。此外，考虑到不同用户...
绿茶加快开机速度小程序
08-03
绿茶加快开机速度小程序会扫描并分析这些启动项，识别出非必要的自启动软件，并允许用户禁用它们，从而减少开机时的资源消耗。 其次，该程序可能包含“补丁”功能，如压缩包中的"绿茶开机加速补丁-减少滚动圈.exe...
电脑开机慢怎么办.docx
09-27
5. **系统瘦身**：随着时间的推移，系统盘中会积累很多可有可无的文件，如系统备份、日志文件等。这些文件不仅占用磁盘空间，还可能影响系统运行速度。使用“系统瘦身”功能，可以智能检测并清理这些文件，以释放...
Windows变慢原因分析及解决方法CHM电子书.rar)
06-01
2. **系统垃圾文件积累**：日积月累的临时文件、系统日志、无效注册表项等会占用硬盘空间，降低系统性能。 3. **病毒与恶意软件**：这些程序常驻内存，消耗系统资源，影响系统运行速度。 4. **硬件老化**：硬盘、...
记录电脑开关机时间，算平均关机时间
06-02
7. **统计分析**：积累一段时间的数据后，可以计算平均关机时间。这涉及到数据的排序、累加和除法运算。可以使用Python的`statistics`模块来计算平均值。 8. **可视化展示**：为了更好地理解数据，可以使用...
Android 源码解读-应用是如何启动的
maniuT的博客
04-28 1729
作为多年的 Android 开发，写了不少应用，但是一个App到底是怎么启动起来的？你要说桌面点一下就启动了，那也对。但是它的启动过程呢？带着这样的疑问，咱们来一步步学习。
Android Framework原理 -- service_manager注册服务流程分析
嘴巴吃糖了
10-15 672
不管是系统服务，还是自定义服务，都是会被注册到sm中，那么我们就拿AMS来举例，看AMS如何注册到sm中的。在第一小节中我们知道，init进程启动之后，创建zygote进程，zygote孵化出system_server进程，在system_server进程中持有了像AMS、PMS、WMS等系统服务，所以我们先看下AMS是如何启动的。
windows开关机日志事件ID
爱意随风起，人生不可弃。
06-08 8631
win10开关机日志—30 开机，7002 关机 win7开关机日志—6005开机 6006关机
Android开机阶段log分析
当初游的快了一点
03-26 1万+
Android开机阶段log分析 标签（空格分隔）： 开关机流程 Android开机阶段log分析 bootchart工具查看开机过程 打印开机过程各阶段的时间 kernel Init进程 Zygote进程 SystemServer进程 开机动画log bootchart工具查看开机过程 Android版本：7.0 平台：高通和MTK 输出： 局部放大图： ...
Android 开机优化
azhengye的专栏
05-04 1万+
本文阐述开机优化的解决过程
Android启动过程
风和先行的专栏
10-23 2139
SystemServer启动过程 1.设置时间、时区、语言等； 2.startBootstrapServices：读配置，启动开机必备的服务：AMS、PMS等等 startCoreServices：其他重要服务，DropBox、电池、应用使用情况服务等 startOtherServices：通知、蓝牙、wifi等设备相关服务。 3.在AMS的systemReady 中调用startHomeActivityLocked 启动launcher。 4.Launcher启动后，会在Activit...
如何排查开机/重启时间长问题
bestwu0666的博客
06-15 1237
如何查看kernel启动耗时在ylog的开机log(在poweron的目录中)中, 在0-kernel.log中搜索"init first stage started!"，看看kernel log时间戳，得到kernel启动时间是多长
Android开机速度优化（第三篇）
热门推荐
wd229047557的博客
06-22 1万+
参考地址：https://www.jianshu.com/p/6dba42c022a9问题描述开机时间相对参考机过慢，大约慢15s左右。Android 系统7.0。问题分析开机问题涉及的层次较多，大致有bootloader--&gt;kernel--&gt;Zygote--&gt;PMS--&gt;AMS--&gt;Launcher可以借助bootchart来分析，也可以直接通过log分析。不幸的...
Android 分析EventLog
z9722的博客
02-26 1348
一.查看EventLog 使用命令： logcat -b events 二.logtags生成 2.1开机event log分析 1.获取开机时的event log： logcat -b events | grep boot_progress 输出： I boot_progress_start: 20726 I boot_progress_preload_start: 23255 I boot_progress_preload_end: 26806 I boot_progress_system_ru
Android 开机内存分析
最新发布
05-01
要对Android开机时的内存进行分析，可以使用以下方法[^1]： 1. 首先，通过adb shell命令获取系统当前内存状态。可以使用以下命令： ``` adb shell cat /proc/meminfo ``` 该命令可以输出系统的内存信息，包括总内存、空闲内存、缓存等。 2. 如果机器在运行的过程中MemAvailable持续减小，就可以怀疑系统中存在内存泄漏。但是并不是MemAvailable减小就一定存在内存泄漏，还要进一步分析减少的内存被什么占用了。可以使用以下命令获取内存占用信息： ``` adb shell cat /proc/meminfo | grep "Active(anon)\|Inactive(anon)\|SUnreclaim" ``` 该命令可以输出Active(anon)、Inactive(anon)和SUnreclaim所占用的内存情况。其中，Active(anon)+Inactive(anon)所占用的内存增加，说明用户进程占用的内存增加。如果SUnreclaim所占用的内存增加，说明kernel占用的内存增加。 3. 知道了减少的内存是被用户进程还是kernel占用之后，要分析是哪个用户进程或内核哪部分占用了内存。可以使用以下命令： ``` adb shell dumpsys meminfo ``` 该命令可以输出每个进程的内存占用情况，包括Native Heap、Dalvik Heap等。 4. 如果需要进一步分析某个进程的内存占用情况，可以使用以下命令： ``` adb shell dumpsys meminfo <pid> ``` 其中，<pid>为进程的ID号。 另外，如果需要分析slab占用情况，可以使用以下命令： ``` adb shell cat /proc/slabinfo ``` 该命令可以输出所有slab的信息，包括名称、对象大小、分配情况等。

“相关推荐”对你有帮助么？

  • 非常没帮助
  • 没帮助
  • 一般
  • 有帮助
  • 非常有帮助
提交
评论
添加红包

请填写红包祝福语或标题

个

红包个数最小为10个

元

红包金额最低5元

当前余额3.43元 前往充值 >
需支付：10.00元
成就一亿技术人!
领取后你会自动成为博主和红包主的粉丝 规则
hope_wisdom
发出的红包

打赏作者

坂田民工

你的鼓励将是我创作的最大动力

¥1 ¥2 ¥4 ¥6 ¥10 ¥20
扫码支付：¥1
获取中
扫码支付

您的余额不足，请更换扫码支付或充值

打赏作者

实付元
使用余额支付
点击重新获取
扫码支付
钱包余额 0

抵扣说明：

1.余额是钱包充值的虚拟货币，按照1:1的比例进行支付金额的抵扣。
2.余额无法直接购买下载，可以购买VIP、付费专栏及课程。

余额充值