我试图了解dmesg
时间戳,发现很难将其转换为 java 日期/自定义日期格式。
示例 dmesg 日志:
[14614.647880] airo(eth1): link lost (missed beacons)
那么如何转换14614.647880
为标准日期呢?
理解dmesg
时间戳非常简单:它是自内核启动以来的时间,以秒为单位。因此,有了启动时间 ( uptime
),您可以将秒数相加并以您喜欢的任何格式显示。
或者更好的是,您可以使用 的-T
命令行选项dmesg
并解析人类可读的格式。
从手册页:
-T, --ctime
Print human readable timestamps. The timestamp could be inaccurate!
The time source used for the logs is not updated after system SUSPEND/RESUME.
在回答博士的帮助下,我编写了一个解决方法,可以将转换放入您的 .bashrc。如果您没有任何时间戳或已经正确的时间戳,它不会破坏任何东西。
dmesg_with_human_timestamps () {
$(type -P dmesg) "$@" | perl -w -e 'use strict;
my ($uptime) = do { local @ARGV="/proc/uptime";<>}; ($uptime) = ($uptime =~ /^(\d+)\./);
foreach my $line (<>) {
printf( ($line=~/^\[\s*(\d+)\.\d+\](.+)/) ? ( "[%s]%s\n", scalar localtime(time - $uptime + $1), $2 ) : $line )
}'
}
alias dmesg=dmesg_with_human_timestamps
此外,很好地阅读了 dmesg 时间戳转换逻辑以及在没有时间戳时如何启用时间戳: https: //supportcenter.checkpoint.com/supportcenter/portal?eventSubmit_doGoviewsolutiondetails= &solutionid=sk92677
对于 RHEL/CentOS 6 等没有“dmesg -T”的系统,我喜欢之前lucas-cimon提供的“dmesg_with_human_timestamps”功能。不过,我们的一些正常运行时间较长的盒子有点麻烦。事实证明,dmesg 中的内核时间戳是从单个 CPU 保存的正常运行时间值得出的。随着时间的推移,这与实时时钟不同步。因此,最近 dmesg 条目的最准确转换将基于 CPU 时钟而不是 /proc/uptime。例如,在此处的特定 CentOS 6.6 机器上:
# grep "\.clock" /proc/sched_debug | head -1
.clock : 32103895072.444568
# uptime
15:54:05 up 371 days, 19:09, 4 users, load average: 3.41, 3.62, 3.57
# cat /proc/uptime
32123362.57 638648955.00
考虑到 CPU 正常运行时间以毫秒为单位,这里有近 5 1/2 小时的偏移量。所以我修改了脚本并在过程中将其转换为原生 bash:
dmesg_with_human_timestamps () {
FORMAT="%a %b %d %H:%M:%S %Y"
now=$(date +%s)
cputime_line=$(grep -m1 "\.clock" /proc/sched_debug)
if [[ $cputime_line =~ [^0-9]*([0-9]*).* ]]; then
cputime=$((BASH_REMATCH[1] / 1000))
fi
dmesg | while IFS= read -r line; do
if [[ $line =~ ^\[\ *([0-9]+)\.[0-9]+\]\ (.*) ]]; then
stamp=$((now-cputime+BASH_REMATCH[1]))
echo "[$(date +"${FORMAT}" --date=@${stamp})] ${BASH_REMATCH[2]}"
else
echo "$line"
fi
done
}
alias dmesgt=dmesg_with_human_timestamps
所以KevZero要求一个不那么笨拙的解决方案,所以我想出了以下内容:
sed -r 's#^\[([0-9]+\.[0-9]+)\](.*)#echo -n "[";echo -n $(date --date="@$(echo "$(grep btime /proc/stat|cut -d " " -f 2)+\1" | bc)" +"%c");echo -n "]";echo -n "\2"#e'
这是一个例子:
$ dmesg|tail | sed -r 's#^\[([0-9]+\.[0-9]+)\](.*)#echo -n "[";echo -n $(date --date="@$(echo "$(grep btime /proc/stat|cut -d " " -f 2)+\1" | bc)" +"%c");echo -n "]";echo -n "\2"#e'
[2015-12-09T04:29:20 COT] cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[2015-12-09T04:29:23 COT] wlp3s0: authenticate with dc:9f:db:92:d3:07
[2015-12-09T04:29:23 COT] wlp3s0: send auth to dc:9f:db:92:d3:07 (try 1/3)
[2015-12-09T04:29:23 COT] wlp3s0: authenticated
[2015-12-09T04:29:23 COT] wlp3s0: associate with dc:9f:db:92:d3:07 (try 1/3)
[2015-12-09T04:29:23 COT] wlp3s0: RX AssocResp from dc:9f:db:92:d3:07 (capab=0x431 status=0 aid=6)
[2015-12-09T04:29:23 COT] wlp3s0: associated
[2015-12-09T04:29:56 COT] thinkpad_acpi: EC reports that Thermal Table has changed
[2015-12-09T04:29:59 COT] i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
[2015-12-09T05:00:52 COT] thinkpad_acpi: EC reports that Thermal Table has changed
如果您希望它执行得更好,请将 proc 中的时间戳放入变量中:)
在最新版本的 dmesg 中,您只需调用dmesg -T
.
如果您没有例如在 Andoid 上的-T
选项,您可以使用该版本。以下还解决了其他一些问题:dmesg
busybox
[0.0000]
有一些看起来像错位的颜色信息,前缀如<6>
.它的灵感来自这篇博文。
#!/bin/sh
# Translate dmesg timestamps to human readable format
# uptime in seconds
uptime=$(cut -d " " -f 1 /proc/uptime)
# remove fraction
uptime=$(echo $uptime | cut -d "." -f1)
# run only if timestamps are enabled
if [ "Y" = "$(cat /sys/module/printk/parameters/time)" ]; then
dmesg | sed "s/[^\[]*\[/\[/" | sed "s/^\[[ ]*\?\([0-9.]*\)\] \(.*\)/\\1 \\2/" | while read timestamp message; do
timestamp=$(echo $timestamp | cut -d "." -f1)
ts1=$(( $(busybox date +%s) - $uptime + $timestamp ))
ts2=$(busybox date -d "@${ts1}")
printf "[%s] %s\n" "$ts2" "$message"
done
else
echo "Timestamps are disabled (/sys/module/printk/parameters/time)"
fi
但是请注意,此实现非常缓慢。
您需要参考 /proc/stat 中的“btime”,这是系统最近一次启动时的 Unix 纪元时间。然后您可以基于该系统启动时间,然后添加 dmesg 中给出的经过秒数来计算每个事件的时间戳。
对于较旧的 Linux 发行版,另一种选择是使用包装脚本,例如在 Perl 或 Python 中。
在此处查看解决方案:
http://linuxaria.com/article/how-to-make-dmesg-timestamp-human-readable?lang=en http://jmorano.moretrix.com/2012/03/dmesg-human-readable-timestamps/
其他答案似乎没有提到的一个警告是,显示的时间dmesg
没有考虑任何 sleep/suspend time。因此,在某些情况下,使用的通常答案dmesg -T
不起作用,并显示完全错误的时间。
这种情况的解决方法是在已知时间向内核日志写入一些内容,然后使用该条目作为参考来计算其他时间。显然,它只会在最后一次挂起后工作一段时间。
因此,要显示自上次启动以来可能已暂停的机器上最近条目的正确时间,请使用我在此处的其他答案中的类似内容:
# write current time to kernel ring buffer so it appears in dmesg output
echo "timecheck: $(date +%s) = $(date +%F_%T)" | sudo tee /dev/kmsg
# use our "timecheck" entry to get the difference
# between the dmesg timestamp and real time
offset=$(dmesg | grep timecheck | tail -1 \
| perl -nle '($t1,$t2)=/^.(\d+)\S+ timecheck: (\d+)/; print $t2-$t1')
# pipe dmesg output through a Perl snippet to
# convert it's timestamp to correct readable times
dmesg | tail \
| perl -pe 'BEGIN{$offset=shift} s/^\[(\d+)\S+/localtime($1+$offset)/e' $offset
dmesg -T
可能会显示错误的时间,与date
命令输出不同。
解决方法是带 -k、--dmesg 的 journalctl。我正在使用 -k 因为它更短:
journalctl -k
它只会显示内核消息和正确的时间。
仅显示与短语匹配的内核行:
journalctl -kg phrase