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