From mboxrd@z Thu Jan  1 00:00:00 1970
From: Sasha Levin <levinsasha928@gmail.com>
Subject: Adjusting kvm-clock more then 11% (9315508 vs 9311354)
Date: Fri, 27 Jan 2012 08:44:19 -0500
Message-ID: <1327671859.14789.8.camel@lappy>
Mime-Version: 1.0
Content-Type: text/plain; charset="us-ascii"
Content-Transfer-Encoding: 7bit
To: kvm <kvm@vger.kernel.org>
Return-path: <kvm-owner@vger.kernel.org>
Received: from mail-gx0-f174.google.com ([209.85.161.174]:42297 "EHLO
	mail-gx0-f174.google.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org
	with ESMTP id S1752499Ab2A0NoT (ORCPT <rfc822;kvm@vger.kernel.org>);
	Fri, 27 Jan 2012 08:44:19 -0500
Received: by ggnb1 with SMTP id b1so793742ggn.19
        for <kvm@vger.kernel.org>; Fri, 27 Jan 2012 05:44:19 -0800 (PST)
Sender: kvm-owner@vger.kernel.org
List-ID: <kvm.vger.kernel.org>

Hi all,

I've started getting the following warnings while running a vm under load, starting with 3.3-rc1:

[ 5367.103017] ------------[ cut here ]------------
[ 5367.103082] WARNING: at kernel/time/timekeeping.c:863 do_timer+0x360/0x4d0()
[ 5367.103082] Adjusting kvm-clock more then 11% (9315508 vs 9311354)
[ 5367.103082] Pid: 2775, comm: trinity Not tainted 3.3.0-rc1-next-20120125-sasha-00003-gb7da216 #148
[ 5367.103082] Call Trace:
[ 5367.103082]  <IRQ>  [<ffffffff810ac265>] warn_slowpath_common+0x75/0xb0
[ 5367.103082]  [<ffffffff810ac301>] warn_slowpath_fmt+0x41/0x50
[ 5367.103082]  [<ffffffff81100690>] do_timer+0x360/0x4d0
[ 5367.103082]  [<ffffffff811077c0>] ? tick_nohz_handler+0x100/0x100
[ 5367.103082]  [<ffffffff811077c0>] ? tick_nohz_handler+0x100/0x100
[ 5367.103082]  [<ffffffff81107665>] tick_do_update_jiffies64+0x75/0xd0
[ 5367.103082]  [<ffffffff81107870>] tick_sched_timer+0xb0/0xc0
[ 5367.103082]  [<ffffffff810d4161>] __run_hrtimer.clone.24+0x81/0x130
[ 5367.103082]  [<ffffffff810d4cbf>] hrtimer_interrupt+0xef/0x220
[ 5367.103082]  [<ffffffff8106d693>] smp_apic_timer_interrupt+0x63/0xa0
[ 5367.103082]  [<ffffffff8263e833>] apic_timer_interrupt+0x73/0x80
[ 5367.103082]  <EOI>  [<ffffffff81053943>] ? sched_clock+0x13/0x20
[ 5367.103082]  [<ffffffff8110d7eb>] ? lock_acquired+0x13b/0x320
[ 5367.103082]  [<ffffffff81238033>] ? task_dumpable+0x23/0x70
[ 5367.103082]  [<ffffffff8263c3b7>] _raw_spin_lock+0x67/0x70
[ 5367.103082]  [<ffffffff81238033>] ? task_dumpable+0x23/0x70
[ 5367.103082]  [<ffffffff81238033>] task_dumpable+0x23/0x70
[ 5367.103082]  [<ffffffff8123bf2c>] proc_pid_make_inode+0x6c/0x1f0
[ 5367.103082]  [<ffffffff8123c18f>] proc_pident_instantiate+0x1f/0xc0
[ 5367.103082]  [<ffffffff8123ccad>] proc_fill_cache+0x13d/0x170
[ 5367.103082]  [<ffffffff8118ff1e>] ? might_fault+0x4e/0xa0
[ 5367.103082]  [<ffffffff8123c170>] ? proc_pid_instantiate+0xc0/0xc0
[ 5367.103082]  [<ffffffff811e5010>] ? filldir64+0xf0/0xf0
[ 5367.103082]  [<ffffffff811e5010>] ? filldir64+0xf0/0xf0
[ 5367.103082]  [<ffffffff8123cdbb>] proc_pident_readdir+0xdb/0x1c0
[ 5367.103082]  [<ffffffff811e5010>] ? filldir64+0xf0/0xf0
[ 5367.103082]  [<ffffffff8123cef6>] proc_tgid_base_readdir+0x16/0x20
[ 5367.103082]  [<ffffffff811e5270>] vfs_readdir+0xb0/0xd0
[ 5367.103082]  [<ffffffff811d216f>] ? fget+0xaf/0x2d0
[ 5367.103082]  [<ffffffff811d20fc>] ? fget+0x3c/0x2d0
[ 5367.103082]  [<ffffffff811e5374>] sys_getdents+0x84/0xf0
[ 5367.103082]  [<ffffffff8263dcf9>] system_call_fastpath+0x16/0x1b
[ 5367.103082] ---[ end trace aeeafe4ebf839531 ]---

Usually the lower part of the stack changes and is usually the result of calling some syscall, but the part from the EOI and up is the same.

-- 

Sasha.