Bug#918036: linux: uptime after reboot wrong (kvm-clock related?)
Hi Thorsten,
On Wed, Jan 02, 2019 at 04:08:23PM +0000, Thorsten Glaser wrote:
> Package: src:linux
> Version: 4.19.13-1
> Severity: normal
>
> I’ve just rebooted this VM and get:
>
> root@ci-busyapps:~ # uptime
> 16:06:57 up 58 days, 21:22, 1 user, load average: 0.62, 0.98, 0.46
>
> In syslog, I see this:
>
> Jan 2 15:55:01 ci-busyapps CRON[3287]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
> Jan 2 15:56:11 ci-busyapps postfix/anvil[3005]: statistics: max connection rate 1/60s for (smtp:172.26.1.40) at Jan 2 15:52:51
> Jan 2 15:56:11 ci-busyapps postfix/anvil[3005]: statistics: max connection count 1 for (smtp:172.26.1.40) at Jan 2 15:52:51
> Jan 2 15:56:11 ci-busyapps postfix/anvil[3005]: statistics: max cache size 1 at Jan 2 15:52:51
> Jan 2 15:57:20 ci-busyapps sensord: sensord stopped
> Jan 2 15:58:05 ci-busyapps dhclient[1031]: DHCPREQUEST of 172.26.1.40 on eth0 to 172.26.100.2 port 67
> Jan 2 15:58:05 ci-busyapps dhclient[1031]: DHCPACK of 172.26.1.40 from 172.26.100.2
> Jan 2 15:58:05 ci-busyapps dhclient[1031]: bound to 172.26.1.40 -- renewal in 19447 seconds.
> Jan 2 15:59:04 ci-busyapps shutdown[7314]: shutting down for system reboot
> Jan 2 15:59:05 ci-busyapps init: Switching to runlevel: 6
> Jan 2 15:59:09 ci-busyapps jenkins: jenkins: client (pid 1579) exited with 143 status
> Jan 2 15:59:10 ci-busyapps ntpd[1608]: ntp engine exiting
> Jan 2 15:59:10 ci-busyapps ntpd[1607]: Terminating
> Jan 2 15:59:10 ci-busyapps postfix/master[22032]: terminating on signal 15
> Jan 2 15:59:18 ci-busyapps syslogd: exiting on signal 15
> Jan 2 16:00:47 ci-busyapps syslogd (GNU inetutils 1.9.4): restart
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] Linux version 4.19.0-1-amd64 (debian-kernel@lists.debian.org) (gcc version 8.2.0 (Debian 8.2.0-13)) #1 SMP Debian 4.19.13-1 (2018-12-30)
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.19.0-1-amd64 root=/dev/mapper/vg--ci--busyapps-lv--root ro net.ifnames=0 kaslr nomodeset
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] x86/fpu: x87 FPU will use FXSAVE
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] BIOS-provided physical RAM map:
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000dfffdfff] usable
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] BIOS-e820: [mem 0x00000000dfffe000-0x00000000dfffffff] reserved
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000021fffffff] usable
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] NX (Execute Disable) protection: active
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] SMBIOS 2.4 present.
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] DMI: Bochs Bochs, BIOS Bochs 01/01/2011
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] Hypervisor detected: KVM
> Jan 2 16:00:47 ci-busyapps vmunix: [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
> Jan 2 16:00:47 ci-busyapps vmunix: [5087690.332663] kvm-clock: cpu 0, msr 3ffd7001, primary cpu clock
> Jan 2 16:00:47 ci-busyapps vmunix: [5087690.332663] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
> Jan 2 16:00:47 ci-busyapps vmunix: [5087690.332665] tsc: Detected 3064.488 MHz processor
As a datapoint: This sounds familiar in the sense that it was reported
earlier https://lore.kernel.org/lkml/20181106054212.GA31768@nautica/ .
Regards,
Salvatore
Reply to: