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 !!!