Figure

低优先级

■ 我的分析
之前的时间戳更新通过suspend的UTC进行更正。当前设备没有suspend的状态，所以kernel相关日志的时间没有更新

■ 下一步计划
查看代码修改逻辑，不进入suspend也能更新时间戳

■ 我的分析
kernel的时间是根据system/core/logd/LogKlog.cpp文件中的sniffTime函数来修正时间的
这个函数在进入suspend时，会根据log中的UTC来修正时间戳
但是设备不会进入suspend，所以时间不会更新，并且查看日志也没有UTC
echo mem > /sys/power/state
01-01 10:21:52.699 0 0 I PM : suspend entry (deep)
01-01 10:21:52.703 0 0 I PM : Syncing filesystems ... done.
01-01 10:21:59.116 0 0 E PM : Device a800000.ssusb failed to suspend: error -16
01-01 10:21:59.116 0 0 E PM : Some devices failed to suspend, or early wake event detected
01-01 10:22:09.110 0 0 I PM : suspend exit
查看log并没有UTC的时间
对比其他的设备，打印的log为
04-08 02:34:22.215 0 0 I PM : suspend entry (deep) UTC:2023-04-07 18:34:22
04-08 02:34:22.473 0 0 E PM : dpm_run_callback(): platform_pm_suspend.cfi_jt+0x0/0x4 returns -16
04-08 02:34:22.473 0 0 E PM : Device 4e00000.ssusb failed to suspend: error -16
04-08 02:34:22.473 0 0 E PM : Some devices failed to suspend, or early wake event detected
04-08 02:34:23.055 0 0 I PM : suspend exit UTC:2023-04-07 18:34:23
查看kernel日志中并没有UTC时间

■ 下一步计划
主动打印UTC，然后修正时间戳

■ 目前进度
查看源码发现确实没有打印UCT
pr_info("suspend entry (%s)\n", mem_sleep_labels[state]);
error = enter_state(state);
if (error) {
suspend_stats.fail++;
dpm_save_failed_errno(error);
} else {
suspend_stats.success++;
}
pr_info("suspend exit\n");

对比其他设备，发现UTC和CONFIG_HW_PM_DEBUG有关系
pr_info("suspend entry (%s) UTC:%d-%02d-%02d %02d:%02d:%02d\n",
mem_sleep_labels[state], tm.tm_year + 1900, tm.tm_mon + 1,
tm.tm_mday, tm.tm_hour, tm.tm_min, tm.tm_sec);
#else /* CONFIG_HW_PM_DEBUG /
pr_info("suspend entry (%s)\n", mem_sleep_labels[state]);
#endif / CONFIG_HW_PM_DEBUG /
error = enter_state(state);
if (error) {
suspend_stats.fail++;
dpm_save_failed_errno(error);
} else {
suspend_stats.success++;
}
#ifdef CONFIG_HW_PM_DEBUG
time64_to_tm(ktime_get_real_seconds(), 0, &tm);
pr_info("suspend exit UTC:%d-%02d-%02d %02d:%02d:%02d\n",
tm.tm_year + 1900, tm.tm_mon + 1, tm.tm_mday,
tm.tm_hour, tm.tm_min, tm.tm_sec);
#else / CONFIG_HW_PM_DEBUG */
pr_info("suspend exit\n");

■ 下一步计划
尝试添加UTC，然后主动进入suspend，看看kernel日志的时间是否同步

■ 当前进度
已经添加修改，等待编译验证
pr_info("suspend entry (%s) UTC:%d-%02d-%02d %02d:%02d:%02d\n",
mem_sleep_labels[state], tm.tm_year + 1900, tm.tm_mon + 1,
tm.tm_mday, tm.tm_hour, tm.tm_min, tm.tm_sec);
pr_info("suspend entry (%s)\n", mem_sleep_labels[state]);

error = enter_state(state);
if (error) {
suspend_stats.fail++;
dpm_save_failed_errno(error);
} else {
suspend_stats.success++;
}

time64_to_tm(ktime_get_real_seconds(), 0, &tm);
pr_info("suspend exit UTC:%d-%02d-%02d %02d:%02d:%02d\n",
tm.tm_year + 1900, tm.tm_mon + 1, tm.tm_mday,
tm.tm_hour, tm.tm_min, tm.tm_sec);

pr_info("suspend exit\n");
■ 下一步计划
看是否会打UTC日志，然后主动触发suspend，kernel时间会不会更新

■ 目前进度
添加UTC日志，主动触发suspend，查看日志添加UTC生效，但是kernel日志时间戳还是没有更新，
怀疑是没有走LogKlog.cpp文件的sniffTime函数来更新时间戳
01-02 07:45:57.732 0 0 I PM : suspend entry (deep) UTC:2023-04-13 18:31:30
01-02 07:45:57.732 0 0 I PM : Syncing filesystems ... done.
01-02 07:45:57.949 0 0 E PM : Device a800000.ssusb failed to suspend: error -16
01-02 07:45:57.949 0 0 E PM : Some devices failed to suspend, or early wake event detected
01-02 07:45:58.325 0 0 I PM : suspend exit UTC:2023-04-13 18:31:30
■ 下一步计划
调查原因

1,添加调试信息
+inline void debugLog(const char *format,...)
+{
+ va_list args;
+ static int fdDmesg = -1;
+ char buffer[0xff] = {0};
+ va_start(args, format);
+ vsprintf(buffer,format,args);
+ va_end(args);
+ fdDmesg = open("/dev/kmsg", O_WRONLY);
+ if(0 > fdDmesg){
+ return;
+ }
+ write(fdDmesg, buffer, strlen(buffer)+1);
+ close(fdDmesg);
+}

2.userdebug上还未复现

现在手动更新时间发现也无效果
要从下面两个地方进行入手
1.机器上无RTC的电池，是否是硬件的问题
2.为什么彻底断电后，开机后显示的时间，是版本编译的时间，尓不是UTC时间

初步怀疑是RTC导致的问题，

04-28 21:58:27.378 1039 1039 W JobScheduler: !!! RTC not yet good; tracking time updates for job scheduling

1.因为硬件已经封板，故RTC的方法，不行，
2.提case给高通，看是否的相关的方法，case号：06620740

临时规避方案

commit:
https://dev.thundercomm.com/gerrit/c/general/platform/system/logging/+/199850