Internally log messages contain the timestamps relative to the Host boot
time, while Container could start much later than the Host boots and the
timestamps should be shifted accordingly.

Otherwise "dmesg -H" reports data from the future.

Note:
 * time_ns->offsets.boottime is (ve_uptime - host_uptime),
   i.e. negative for Containers created on this Host
 * ring buffer contains timestamps relative to the Host boot

 => we have to add the .boottime offset to the timestamp of Host
    to get the msg entry timestamp relative to Container boottime.

Note 2: we cannot use timespec64_to_ns() in ve_timens_sub_boottime_ns()
        to convert .boottime to nsec because timespec64_to_ns() is not
        designed to handle negative offsets, it tries to avoid the
        overflow and returns KTIME_MAX instead for negative offsets.

Based on the vz7 commit
  e20414197aef ("kmsg: Virtualize timestamps in /dev/ksmg output")

https://jira.sw.ru/browse/PSBM-145313

Signed-off-by: Konstantin Khorenko <khore...@virtuozzo.com>

v2 changes:
  * ve_timens_add_boottime_ns() should return "nsec", not 0 in case
    Container is not started yet

---
 kernel/printk/printk.c | 21 +++++++++++++++++++++
 1 file changed, 21 insertions(+)

diff --git a/kernel/printk/printk.c b/kernel/printk/printk.c
index c68e3b468ab7..57a8b708ecb3 100644
--- a/kernel/printk/printk.c
+++ b/kernel/printk/printk.c
@@ -600,6 +600,25 @@ static void append_char(char **pp, char *e, char c)
                *(*pp)++ = c;
 }
 
+static inline u64 ve_timens_add_boottime_ns(u64 nsec)
+{
+       struct time_namespace *ve_time_ns;
+       struct timens_offsets *ns_offsets;
+
+       ve_time_ns = ve_get_time_ns(get_exec_env());
+       if (unlikely(!ve_time_ns)) {
+               /* container not yet started */
+               return nsec;
+       }
+
+       ns_offsets = &ve_time_ns->offsets;
+       nsec += ns_offsets->boottime.tv_sec * NSEC_PER_SEC;
+       nsec += ns_offsets->boottime.tv_nsec;
+       put_time_ns(ve_time_ns);
+
+       return nsec;
+}
+
 static ssize_t info_print_ext_header(char *buf, size_t size,
                                     struct printk_info *info)
 {
@@ -614,6 +633,8 @@ static ssize_t info_print_ext_header(char *buf, size_t size,
        caller[0] = '\0';
 #endif
 
+       /* shift the timestamp on the Container uptime value */
+       ts_usec = ve_timens_add_boottime_ns(ts_usec);
        do_div(ts_usec, 1000);
 
        return scnprintf(buf, size, "%u,%llu,%llu,%c%s;",
-- 
2.31.1

_______________________________________________
Devel mailing list
Devel@openvz.org
https://lists.openvz.org/mailman/listinfo/devel

Reply via email to