bmetrics_service | boot metrics service, 用于记录统计启动性能信息,读取/dev/bmetrics可以获取到这些信息 |
# use memorydump
memorydump Sets the debug cookies, copies MMU info into reset_info asinfo,
sets the secure monitor(TZ) dump buffer, starts tracelogger
Usage :
-t : Enable kernel tracing
-r : Enable ramdump functionality
-d : Dump mode - full/mini_rawdump/mini_dload/nodump
-U : To set the UID,GID
qcore | 高通QNX电源管理模块 |
vmm_service | 虚拟机管理服务,参考 80-PM231-5 | ||
vmm_lifecycle | 虚拟机生命管理模块 |
在QNX 虚拟机管理程序环境中,虚拟机体现为qvm进程。qvm 进程是在内核外部的Hypervisor中运行的操作系统进程。
aps
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 4 00009 NTC health_monitor[cpu.c:234]: Partition name id | Budget | Max | Used
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 4 00010 NTC health_monitor[cpu.c:235]: --------------------|--------|--------|--------
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 4 00011 NTC health_monitor[cpu.c:266]: System 0 | 32.0% | 100.0% | 38.77%
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 4 00012 NTC health_monitor[cpu.c:266]: aps_gvm 1 | 35.0% | 100.0% | 6.92%
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 4 00013 NTC health_monitor[cpu.c:266]: aps_ic 2 | 20.0% | 100.0% | 2.86%
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 4 00014 NTC health_monitor[cpu.c:266]: aps_camera 3 | 5.0% | 100.0% | 0.00%
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 4 00015 NTC health_monitor[cpu.c:266]: aps_ipc 4 | 3.0% | 100.0% | 0.42%
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 4 00016 NTC health_monitor[cpu.c:266]: aps_3rd_party 5 | 5.0% | 100.0% | 0.00%
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 4 00017 NTC health_monitor[cpu.c:272]: --------------------|--------|--------|--------
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 4 00018 NTC health_monitor[cpu.c:277]: Total | 100.0% | | 48.97%
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00019 NTC health_monitor[mem.c:372]: Mem Total(KB) Used(KB) Free(KB)
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00020 NTC health_monitor[mem.c:373]: -------------------------------------------------------------
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00021 NTC health_monitor[mem.c:380]: sysram 2974196 1088656 1885540
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00022 NTC health_monitor[mem.c:380]: gvm_secmem 327680 0 327680
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00023 NTC health_monitor[mem.c:380]: gvm_pmem 40960 72 40888
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00024 NTC health_monitor[mem.c:380]: qcpe 16384 16384 0
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00025 NTC health_monitor[mem.c:380]: mdf_mem 10240 8192 2048
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00026 NTC health_monitor[mem.c:380]: qseecom 20480 8032 12448
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00027 NTC health_monitor[mem.c:380]: mm_dma 1310720 96792 1213928
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00028 NTC health_monitor[mem.c:380]: smmu_s1_pt 16384 1796 14588
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00029 NTC health_monitor[mem.c:380]: dma 65536 12932 52604
2024/03/06 13:49:54.979 health_monitor p:3158091 t: 5 00030 NTC health_monitor[mem.c:382]: -------------------------------------------------------------
2024/03/06 13:49:54.980 health_monitor p:3158091 t: 1 00031 NTC health_monitor[cmd.c:27]: Run cmd 'find /dev/disk -type l -exec echo {} \; -exec df -h {} \;'
Android 侧是亮屏的
03-06 13:49:51.561808 1691 2037 I CAR.POWER: send wait for vhal
03-06 13:49:51.562483 1691 2037 I CAR.POWER: setPowerState=WAIT_FOR_VHAL(1) param=0
03-06 13:49:51.577448 1691 2012 I CAR.POWER: Received AP_POWER_STATE_REQ=ON(0) param=0
03-06 13:49:51.612065 1691 2037 I CAR.POWER: send on
03-06 13:49:51.613406 1691 2037 I CAR.POWER: setPowerState=ON(6) param=0
qnx 测
启动服务
Daemon launched. Starting to output traces...
Line 18762: 1970/01/01 08:00:01.721 bmetrics p: 16388 t: 1 00033 NTC 1694295[us]: watchdog: LAUNCH
Line 18765: 1970/01/01 08:00:01.722 bmetrics p: 16388 t: 1 00034 NTC 1695272[us]: memorydump: LAUNCH
Line 18768: 1970/01/01 08:00:01.723 bmetrics p: 16388 t: 1 00035 NTC 1696248[us]: syscache_service: LAUNCH
Line 18771: 1970/01/01 08:00:01.724 bmetrics p: 16388 t: 1 00036 NTC 1697377[us]: pil_service: LAUNCH
Line 18782: 1970/01/01 08:00:01.726 bmetrics p: 16388 t: 1 00038 NTC 1699178[us]: smmu_service: LAUNCH
vmm 虚拟机管理服务启动
2024/01/01 08:00:01.158 bmetrics p: 16388 t: 1 00255 NTC 2987813[us]: msg_channel: LAUNCH
2024/01/01 08:00:01.161 bmetrics p: 16388 t: 1 00256 NTC 2990590[us]: vmm_service: LAUNCH
2024/01/01 08:00:01.165 bmetrics p: 16388 t: 1 00257 NTC 2994771[us]: vmm_lifecycle: LAUNCH
时间同步
2024/01/01 08:00:01.172 AIS p: 94261 t: 14 00272 NTC [High][MAX96712_DMS] max96712_lib.c:1008: Power up sensorlib 0 return 0
2024/01/01 08:00:01.179 AIS p: 94261 t: 14 00273 NTC [High][MAX96712_DMS] max96712_lib.c:1220: max96712_sensor_detect_device()
2024/01/01 08:00:01.179 AIS p: 94261 t: 14 00274 NTC [High][MAX96712_DMS] max96712_lib.c:1233: max96712 chip id: 0xa0
2024/01/01 08:00:01.179 AIS p: 94261 t: 14 00275 NTC [High][MAX96712_DMS] max96712_lib.c:958: deserializer_initialize start .
2024/01/01 08:00:01.187 AIS p: 94261 t: 14 00276 NTC [High][MAX96712_DMS] max96712_lib.c:993: deserializer_initialize end return result : 0 .
2024/01/01 08:00:01.207 bmetrics p: 16388 t: 1 00258 NTC 3036977[us]: vm_ssr_be: LAUNCH
2024/01/01 08:00:01.208 systime p: 86048 t: 2 00017 NTC systime[systime_rtc.c:181]: Time read from RTC: 1709704162, Wed Mar 6 13:49:22 2024
2024/01/01 08:00:01.208 systime p: 86048 t: 2 00018 NTC systime[systime_mgr.c:353]: Update system time to: 1709704162, Wed Mar 6 13:49:22 2024
2024/03/06 13:49:22.000 systime p: 86048 t: 2 00019 NTC systime[systime_mgr.c:177]: Current system time (after reading RTC) is 1709704162, Wed Mar 6 13:49:22 2024
2024/03/06 13:49:22.000 systime p: 86048 t: 2 00020 NTC systime[systime_ipc.c:106]: ipc is not ready
2024/03/06 13:49:22.000 systime p: 86048 t: 2 00021 ERR systime[systime_mcutime.c:39]: Failed to pub topic uartrpc_svc/time/req
display
2024/03/06 13:49:22.044 megapm p:1056860 t: 1 00003 ERR mega_pm[mpm_suspend.c:41]: megapm_gpio_init() end, heart_beat:78 sleep_rdy:74 sleep_done:255
2024/03/06 13:49:22.044 mega_ipc p:1056860 t: 1 00004 INF mega_pm[iceoryx_connector.cpp:312]: IceoryxConnector::setListener()
2024/03/06 13:49:22.044 mega_ipc p:1056860 t: 1 00005 INF mega_pm[megaipc_impl.cpp:72]: MegaIpcApi::init() begin
2024/03/06 13:49:22.044 mega_ipc p:1056860 t: 1 00006 ERR mega_pm[iceoryx_connector.cpp:98]: enable random is 0
2024/03/06 13:49:22.044 mega_ipc p:1056860 t: 1 00007 INF mega_pm[iceoryx_connector.cpp:52]: Init Iceroyx App Runtime: mega_pm begin
2024/03/06 13:49:22.046 backlight p: 0 t: 0 00000 SHD -----ONLINE-----
2024/03/06 13:49:22.046 backlight p:1060958 t: 1 00000 ERR backlight_be[backlight_be.c:284]: pthread_create enter
2024/03/06 13:49:22.046 backlight p:1060958 t: 1 00001 ERR backlight_be[backlight_be.c:272]: bl_hab_start enter
2024/03/06 13:49:22.047 backlight p:1060958 t: 2 00002 ERR backlight_be[backlight_be.c:215]: trying to open HAB socket
2024/03/06 13:49:22.053 slm p: 0 t: 0 00000 SHD -----ONLINE-----
2024/03/06 13:49:22.056 bmetrics p: 16388 t: 1 00268 NTC 3093648[us]: uartrpc_service: LAUNCH
2024/03/06 13:49:22.059 mega_ipc p: 0 t: 0 00000 SHD -----ONLINE-----
2024/03/06 13:49:22.060 backlight p: 0 t: 0 00000 SHD -----ONLINE-----
2024/03/06 13:49:22.061 display_manager p: 0 t: 0 00000 SHD -----ONLINE-----
2024/03/06 13:49:22.061 display_manager p:1060962 t: 1 00000 NTC display_manager[display_manager.c:12]: display manager start ...
2024/03/06 13:49:22.061 display_manager p:1060962 t: 1 00001 NTC display_manager[dm_policy.c:598]: start ipc_init...
背光
2024/03/06 13:49:22.062 bridgechip p: 0 t: 0 00000 SHD -----ONLINE-----
2024/03/06 13:49:22.062 backlight p:1060960 t: 1 00001 INF backlight_resmgr[parse_dalconfig.c:138]: find BL_CTRL_TYPE
2024/03/06 13:49:22.062 backlight p:1060960 t: 1 00002 ERR backlight_resmgr[parse_dalconfig.c:163]: deccnt 26
2024/03/06 13:49:22.062 backlight p:1060960 t: 1 00003 ERR backlight_resmgr[parse_dalconfig.c:180]: backlight config num is 2
2024/03/06 13:49:22.062 backlight p:1060960 t: 1 00004 WAR backlight_resmgr[parse_dalconfig.c:22]: ParseBLConfiglist num 26
2024/03/06 13:49:22.062 backlight p:1060960 t: 1 00005 WAR backlight_resmgr[parse_dalconfig.c:29]: backlight port type 1
2024/03/06 13:49:22.062 backlight p:1060960 t: 1 00006 WAR backlight_resmgr[parse_dalconfig.c:62]: backlight path /dev/backlight/vbl_1, lcdtype 1
2024/03/06 13:49:22.062 backlight p:1060960 t: 1 00007 WAR backlight_resmgr[parse_dalconfig.c:45]: backlight port type 2
2024/03/06 13:49:22.062 backlight p:1060960 t: 1 00008 WAR backlight_resmgr[parse_dalconfig.c:62]: backlight path /dev/backlight/vbl_2, lcdtype 2
2024/03/06 13:49:22.062 backlight p:1060960 t: 1 00009 WAR backlight_resmgr[parse_dalconfig.c:116]: parse finished, num is 26, temp 1
2024/03/06 13:49:22.063 backlight p:1060960 t: 1 00010 WAR backlight_resmgr[parse_dalconfig.c:192]: Cant find BL_DISPLAYER_MGR_EN in DAL. Using default value.
2024/03/06 13:49:22.063 backlight p:1060960 t: 1 00011 NTC backlight_resmgr[parse_dalconfig.c:198]: BL_DISPLAYER_MGR_EN 0
2024/03/06 13:49:22.063 backlight p:1060960 t: 1 00012 NTC backlight_resmgr[main.c:22]: enter, ver is v2.0
vmm 启动qvm
2024/03/06 13:49:22.289 vmm p:1003602 t: 3 00051 INF vmm_service[vmm_utils.c:867]: Bootconfig parameters end address is 4 bytes aligned
2024/03/06 13:49:22.290 vmm p:1003602 t: 3 00052 INF vmm_service[vmm_utils.c:889]: Ask qvm to load ramdisk @ phys addr = 0xe1760000 continuous size: 20779972 bytes
2024/03/06 13:49:22.292 vmm p:1003602 t: 2 00053 INF vmm_service[vmm_fsm.c:754]: Config Path @/dev/shmem/linux-la-mem.config
2024/03/06 13:49:22.292 vmm p:1003602 t: 2 00054 INF vmm_service[vmm_fsm.c:760]: guest target slot : a host current slot: a Config slot :@/mnt/vm/images/la_dp_enabled_a.config
2024/03/06 13:49:22.293 vmm p:1003602 t: 2 00055 INF vmm_service[vmm_fsm.c:792]: qvm cmdline arg[0] = qvm
2024/03/06 13:49:22.293 vmm p:1003602 t: 2 00056 INF vmm_service[vmm_fsm.c:792]: qvm cmdline arg[1] = @/mnt/vm/images/linux-la_default.config
2024/03/06 13:49:22.293 vmm p:1003602 t: 2 00057 INF vmm_service[vmm_fsm.c:792]: qvm cmdline arg[2] = @/dev/shmem/linux-la-mem.config
2024/03/06 13:49:22.293 vmm p:1003602 t: 2 00058 INF vmm_service[vmm_fsm.c:792]: qvm cmdline arg[3] = @/mnt/vm/images/la_dp_enabled_a.config
2024/03/06 13:49:22.293 vmm p:1003602 t: 2 00059 INF vmm_service[vmm_fsm.c:792]: qvm cmdline arg[4] = @/mnt/vm/images/la_uid_gid.config
2024/03/06 13:49:22.293 bmetrics p: 16388 t: 1 00284 NTC 3330434[us]: launch qvm -name:la,vmid:2: INTERMEDIATE
2024/03/06 13:49:22.294 vmm p:1003602 t: 2 00060 INF vmm_service[vmm_fsm.c:820]: Launched gvm:la vmid:2 pid:1142896 is_recovery:0 slot=a
Abandoned 请求下电
2024/03/06 13:49:25.678 display_manager p:1060962 t: 3 00009 NTC display_manager[dm_policy.c:364]: got topic ControlScreen/ScreenLight/req, value is {"value":{"mode":0,"priority":5,"reason":"ic_service:ScreenBacklightController:UsageModes is Abandoned","type":1}}
2024/03/06 13:49:25.678 display_manager p:1060962 t: 3 00010 ERR display_manager[dm_policy.c:402]: screen state req REASON is ic_service:ScreenBacklightController:UsageModes is Abandoned
2024/03/06 13:49:25.678 display_manager p:1060962 t: 3 00011 NTC display_manager[dm_policy.c:156]: change cluster dm mode form screen off to power off, init state is 1
2024/03/06 13:49:25.678 display_manager p:1060962 t: 3 00012 NTC display_manager[dm_policy.c:157]: update dm mode to 0x3
2024/03/06 13:49:25.678 display_manager p:1060962 t: 3 00013 NTC display_manager[dm_policy.c:100]: set cluster state power off to display dev
2024/03/06 13:49:25.678 ic_service p:2359436 t: 4 00028 INF 1709704165678 <5>screen_backlight_controller.cpp:178 - executeCondition:ControlScreen/ScreenLight/req:{"value":{"mode":0,"priority":5,"reason":"ic_service:ScreenBacklightController:UsageModes is Abandoned","type":1}}
2024/03/06 13:49:25.684 ic_service p:2359436 t: 1 00261 NTC [][INFO]someip.c(491):Waiting event...
开门仪表亮屏
2024/03/06 13:49:25.981 display_manager p:1060962 t: 3 00016 NTC display_manager[dm_policy.c:364]: got topic ControlScreen/ScreenLight/req, value is {"value":{"mode":1,"priority":4,"reason":"ic_service:ScreenBacklightController:UsageModes is Abandoned and is_epb_onand is_dooropen_on","type":1}}
2024/03/06 13:49:25.981 display_manager p:1060962 t: 3 00017 ERR display_manager[dm_policy.c:402]: screen state req REASON is ic_service:ScreenBacklightController:UsageModes is Abandoned and is_epb_onand is_dooropen_on
2024/03/06 13:49:25.981 display_manager p:1060962 t: 3 00018 ERR display_manager[dm_policy.c:295]: current screen_1 is power off, can't turn on backlight !!!
2024/03/06 13:49:25.981 ic_service p:2359436 t: 4 00766 INF 1709704165981 <5>screen_backlight_controller.cpp:178 - executeCondition:ControlScreen/ScreenLight/req:{"value":{"mode":1,"priority":4,"reason":"ic_service:ScreenBacklightController:UsageModes is Abandoned and is_epb_onand is_dooropen_on","type":1}}
车刚上电是会处于abandon模式,此时会发送 mode=0,收到usageMode 信号改变后才会发送 mode=1
2024/03/06 13:52:34.832 ic_service p:2359436 t: 4 12004 WAR 1709704354832 <3>screen_backlight_controller.cpp:145 - screen: 1 state:2 not equal to set state: 1
2024/03/06 13:52:34.832 ic_service p:2359436 t: 4 12005 INF 1709704354832 <5>screen_backlight_controller.cpp:116 - ivi_compositor_screen meet condition is always on time out 0
2024/03/06 13:52:34.832 ic_service p:2359436 t: 4 12006 INF 1709704354832 <5>screen_backlight_controller.cpp:178 - executeCondition:ControlScreen/ScreenLight/req:{"value":{"mode":1,"priority":6,"reason":"ic_service:ScreenBacklightController:is always on","type":1}}
2024/03/06 13:52:34.832 display_manager p:1060962 t: 3 05909 NTC display_manager[dm_policy.c:364]: got topic ControlScreen/ScreenLight/req, value is {"value":{"mode":1,"priority":6,"reason":"ic_service:ScreenBacklightController:is always on","type":1}}
2024/03/06 13:52:34.832 display_manager p:1060962 t: 3 05910 ERR display_manager[dm_policy.c:402]: screen state req REASON is ic_service:ScreenBacklightController:is always on
2024/03/06 13:52:34.832 display_manager p:1060962 t: 3 05911 ERR display_manager[dm_policy.c:295]: current screen_1 is power off, can't turn on backlight !!!
2024/03/06 13:52:34.837 imu p:2396311 t: 2 11168 ERR imu_service[log.c:52]: [raw_data]:type: 3, id: 5515, time: 1709704354837, accl:[-9.749556, -0.112463, 0.033500], gyro:[0.002441, 0.002441, -0.000381]
2024/03/06 13:52:34.837 imu p:2396311 t: 2 11169 ERR imu_service[log.c:52]: [in_fifo]:type: 3, id: 5515, time: 1709704354837, accl:[-9.749556, -0.112463, 0.033500], gyro:[0.002441, 0.002441, -0.000381]
2024/03/06 13:52:34.856 ic_server p:2371732 t: 7 04760 INF 1709704354856 <5>adas_hmi_service_qnx.cpp:222 - isClientConnected value:0
qnx有收到android 下发的 topic 要求亮屏
2024/03/06 13:49:51.708 display_manager p:1060962 t: 3 00734 NTC display_manager[dm_policy.c:364]: got topic ControlScreen/ScreenLight/req, value is {"extension":"","relative":false,"time":13888,"valid":true,"value":{"mode":1,"priority":0,"reason":"android:screen_req:car_power:com.android.car","type":2}}
2024/03/06 13:49:51.708 display_manager p:1060962 t: 3 00735 ERR display_manager[dm_policy.c:402]: screen state req REASON is android:screen_req:car_power:com.android.car
2024/03/06 13:49:51.708 display_manager p:1060962 t: 3 00736 ERR display_manager[dm_policy.c:295]: current screen_2 is power off, can't turn on backlight !!!