Hello,
i get this error msg on a plain 2.6.13-1.1532_FC4smp,
2 'Dual Core AMD Opteron(tm) Processor 275' on a 'Tyan Thunder K8SE'.
$> grep -B3 -A20 "Badness in interruptible_sleep_on_timeout" \
messages.? messages \
> /tmp/var-log-messages.err
In all cases it seems to be related to NFS unmount
(have a automount on some NFS):
messages-Oct 24 21:26:52 devil kernel: SELinux: initialized (dev 0:19, type nfs), uses genfs_contexts
messages:Oct 24 21:28:02 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages-Oct 24 21:28:02 devil kernel:
messages-Oct 24 21:28:02 devil kernel: Call Trace:<ffffffff8033c3d8>{interruptible_sleep_on_timeout+131}
messages-Oct 24 21:28:02 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff8820a367>{:lockd:lockd_down+207}
messages-Oct 24 21:28:02 devil kernel: <ffffffff88220857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages-Oct 24 21:28:02 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages-Oct 24 21:28:02 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages-Oct 24 21:28:02 devil kernel: <ffffffff8010db02>{tracesys+209}
Seems to be no real problem, just annoying ...
bye,
Markus
--
Dipl.-Ing. (FH) Markus Fritz
Just Innovative Software GmbH * Robert-Bosch-Str. 32 * 74081 Heilbronn
messages.1-Oct 21 16:57:08 devil kernel: SELinux: initialized (dev 0:19, type nfs), uses genfs_contexts
messages.1-Oct 21 16:57:36 devil sshd(pam_unix)[26224]: session opened for user fritz by (uid=0)
messages.1-Oct 21 16:58:30 devil sshd(pam_unix)[26224]: session closed for user fritz
messages.1:Oct 21 16:59:42 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages.1-Oct 21 16:59:42 devil kernel:
messages.1-Oct 21 16:59:42 devil kernel: Call Trace:<ffffffff8033c2c8>{interruptible_sleep_on_timeout+131}
messages.1-Oct 21 16:59:42 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff88235367>{:lockd:lockd_down+207}
messages.1-Oct 21 16:59:42 devil kernel: <ffffffff8824b857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages.1-Oct 21 16:59:42 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages.1-Oct 21 16:59:42 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages.1-Oct 21 16:59:42 devil kernel: <ffffffff8010db02>{tracesys+209}
messages.1-Oct 21 17:01:01 devil crond(pam_unix)[26296]: session opened for user root by (uid=0)
messages.1-Oct 21 17:01:01 devil crond(pam_unix)[26296]: session closed for user root
messages.1-Oct 21 17:01:04 devil kernel: SELinux: initialized (dev 0:18, type nfs), uses genfs_contexts
messages.1:Oct 21 17:02:57 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages.1-Oct 21 17:02:57 devil kernel:
messages.1-Oct 21 17:02:57 devil kernel: Call Trace:<ffffffff8033c2c8>{interruptible_sleep_on_timeout+131}
messages.1-Oct 21 17:02:57 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff88235367>{:lockd:lockd_down+207}
messages.1-Oct 21 17:02:57 devil kernel: <ffffffff8824b857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages.1-Oct 21 17:02:57 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages.1-Oct 21 17:02:57 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages.1-Oct 21 17:02:57 devil kernel: <ffffffff8010db02>{tracesys+209}
messages.1-Oct 21 17:03:19 devil kernel: SELinux: initialized (dev 0:18, type nfs), uses genfs_contexts
messages.1:Oct 21 17:18:28 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages.1-Oct 21 17:18:28 devil kernel:
messages.1-Oct 21 17:18:28 devil kernel: Call Trace:<ffffffff8033c2c8>{interruptible_sleep_on_timeout+131}
messages.1-Oct 21 17:18:28 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff88235367>{:lockd:lockd_down+207}
messages.1-Oct 21 17:18:28 devil kernel: <ffffffff8824b857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages.1-Oct 21 17:18:28 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages.1-Oct 21 17:18:28 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages.1-Oct 21 17:18:28 devil kernel: <ffffffff8010db02>{tracesys+209}
messages.1-Oct 21 17:18:37 devil kernel: SELinux: initialized (dev 0:18, type nfs), uses genfs_contexts
messages.1:Oct 21 17:19:58 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages.1-Oct 21 17:19:58 devil kernel:
messages.1-Oct 21 17:19:58 devil kernel: Call Trace:<ffffffff8033c2c8>{interruptible_sleep_on_timeout+131}
messages.1-Oct 21 17:19:58 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff88235367>{:lockd:lockd_down+207}
messages.1-Oct 21 17:19:58 devil kernel: <ffffffff8824b857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages.1-Oct 21 17:19:58 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages.1-Oct 21 17:19:58 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages.1-Oct 21 17:19:58 devil kernel: <ffffffff8010db02>{tracesys+209}
messages.1-Oct 21 17:23:30 devil kernel: SELinux: initialized (dev 0:18, type nfs), uses genfs_contexts
messages.1:Oct 21 17:24:43 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages.1-Oct 21 17:24:43 devil kernel:
messages.1-Oct 21 17:24:43 devil kernel: Call Trace:<ffffffff8033c2c8>{interruptible_sleep_on_timeout+131}
messages.1-Oct 21 17:24:43 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff88235367>{:lockd:lockd_down+207}
messages.1-Oct 21 17:24:43 devil kernel: <ffffffff8824b857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages.1-Oct 21 17:24:43 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages.1-Oct 21 17:24:43 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages.1-Oct 21 17:24:43 devil kernel: <ffffffff8010db02>{tracesys+209}
messages.1-Oct 21 17:30:42 devil kernel: SELinux: initialized (dev 0:18, type nfs), uses genfs_contexts
messages.1:Oct 21 17:31:58 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages.1-Oct 21 17:31:58 devil kernel:
messages.1-Oct 21 17:31:58 devil kernel: Call Trace:<ffffffff8033c2c8>{interruptible_sleep_on_timeout+131}
messages.1-Oct 21 17:31:58 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff88235367>{:lockd:lockd_down+207}
messages.1-Oct 21 17:31:58 devil kernel: <ffffffff8824b857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages.1-Oct 21 17:31:58 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages.1-Oct 21 17:31:58 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages.1-Oct 21 17:31:58 devil kernel: <ffffffff8010db02>{tracesys+209}
messages.1-Oct 21 17:32:15 devil kernel: SELinux: initialized (dev 0:18, type nfs), uses genfs_contexts
messages.1:Oct 21 17:33:44 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages.1-Oct 21 17:33:44 devil kernel:
messages.1-Oct 21 17:33:44 devil kernel: Call Trace:<ffffffff8033c2c8>{interruptible_sleep_on_timeout+131}
messages.1-Oct 21 17:33:44 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff88235367>{:lockd:lockd_down+207}
messages.1-Oct 21 17:33:44 devil kernel: <ffffffff8824b857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages.1-Oct 21 17:33:44 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages.1-Oct 21 17:33:44 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages.1-Oct 21 17:33:44 devil kernel: <ffffffff8010db02>{tracesys+209}
messages.1-Oct 21 17:38:33 devil kernel: SELinux: initialized (dev 0:18, type nfs), uses genfs_contexts
messages.1-Oct 21 17:39:45 devil kernel: SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
messages.1:Oct 21 17:40:44 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages.1-Oct 21 17:40:44 devil kernel:
messages.1-Oct 21 17:40:44 devil kernel: Call Trace:<ffffffff8033c2c8>{interruptible_sleep_on_timeout+131}
messages.1-Oct 21 17:40:44 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff88235367>{:lockd:lockd_down+207}
messages.1-Oct 21 17:40:44 devil kernel: <ffffffff8824b857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages.1-Oct 21 17:40:44 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages.1-Oct 21 17:40:44 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages.1-Oct 21 17:40:44 devil kernel: <ffffffff8010db02>{tracesys+209}
messages.1-Oct 21 17:41:05 devil kernel: SELinux: initialized (dev 0:18, type nfs), uses genfs_contexts
messages.1:Oct 21 17:42:14 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages.1-Oct 21 17:42:14 devil kernel:
messages.1-Oct 21 17:42:14 devil kernel: Call Trace:<ffffffff8033c2c8>{interruptible_sleep_on_timeout+131}
messages.1-Oct 21 17:42:14 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff88235367>{:lockd:lockd_down+207}
messages.1-Oct 21 17:42:14 devil kernel: <ffffffff8824b857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages.1-Oct 21 17:42:14 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages.1-Oct 21 17:42:14 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages.1-Oct 21 17:42:14 devil kernel: <ffffffff8010db02>{tracesys+209}
messages.1-Oct 21 17:44:06 devil sdpd[1943]: terminating...
messages.1-Oct 21 17:44:06 devil hcid[1941]: Exit.
messages.1-Oct 21 17:48:49 devil kernel: SELinux: initialized (dev 0:18, type nfs), uses genfs_contexts
messages.1:Oct 21 17:51:44 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages.1-Oct 21 17:51:44 devil kernel:
messages.1-Oct 21 17:51:44 devil kernel: Call Trace:<ffffffff8033c2c8>{interruptible_sleep_on_timeout+131}
messages.1-Oct 21 17:51:44 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff88235367>{:lockd:lockd_down+207}
messages.1-Oct 21 17:51:44 devil kernel: <ffffffff8824b857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages.1-Oct 21 17:51:44 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages.1-Oct 21 17:51:44 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages.1-Oct 21 17:51:44 devil kernel: <ffffffff8010db02>{tracesys+209}
messages.1-Oct 21 18:01:01 devil crond(pam_unix)[27840]: session opened for user root by (uid=0)
messages.1-Oct 21 18:01:01 devil crond(pam_unix)[27840]: session closed for user root
messages.1-Oct 21 18:06:00 devil kernel: SELinux: initialized (dev 0:18, type nfs), uses genfs_contexts
messages.1-Oct 21 18:54:58 devil kernel: SELinux: initialized (dev 0:18, type nfs), uses genfs_contexts
messages.1-Oct 21 18:58:15 devil kernel: loop: loaded (max 8 devices)
messages.1-Oct 21 18:58:16 devil kernel: SELinux: initialized (dev loop0, type iso9660), uses genfs_contexts
messages.1-Oct 21 18:58:53 devil kernel: SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
messages.1-Oct 21 19:01:01 devil crond(pam_unix)[5253]: session opened for user root by (uid=0)
messages.1-Oct 21 19:01:01 devil crond(pam_unix)[5253]: session closed for user root
messages.1-Oct 21 19:13:59 devil kernel: SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
messages.1-Oct 21 19:22:15 devil kernel: SELinux: initialized (dev 0:19, type nfs), uses genfs_contexts
messages.1-Oct 21 19:26:29 devil sshd(pam_unix)[12539]: session opened for user root by (uid=0)
messages.1-Oct 21 20:01:01 devil crond(pam_unix)[13674]: session opened for user root by (uid=0)
--
messages-Oct 24 17:29:32 devil sshd(pam_unix)[3225]: session opened for user root by root(uid=0)
messages-Oct 24 17:29:41 devil kernel: SELinux: initialized (dev 0:19, type nfs), uses genfs_contexts
messages-Oct 24 17:30:36 devil kernel: loop: loaded (max 8 devices)
messages:Oct 24 17:31:48 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages-Oct 24 17:31:48 devil kernel:
messages-Oct 24 17:31:48 devil kernel: Call Trace:<ffffffff8033c2c8>{interruptible_sleep_on_timeout+131}
messages-Oct 24 17:31:48 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff8820a367>{:lockd:lockd_down+207}
messages-Oct 24 17:31:48 devil kernel: <ffffffff88220857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages-Oct 24 17:31:48 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages-Oct 24 17:31:48 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages-Oct 24 17:31:48 devil kernel: <ffffffff8010db02>{tracesys+209}
messages-Oct 24 17:34:03 devil shutdown: shutting down for system reboot
messages-Oct 24 17:34:03 devil init: Switching to runlevel: 6
messages-Oct 24 17:34:03 devil login(pam_unix)[2473]: session closed for user root
messages-Oct 24 17:34:04 devil dbus: avc: 2 AV entries and 2/512 buckets used, longest chain length 1
messages-Oct 24 17:34:05 devil ntpd[2294]: ntpd exiting on signal 15
messages-Oct 24 17:34:05 devil rpc.statd[1869]: Caught signal 15, un-registering and exiting.
messages-Oct 24 17:34:06 devil auditd[1881]: The audit daemon is exiting.
messages-Oct 24 17:34:06 devil kernel: audit(1130168046.056:151): audit_pid=0 old=1881 by auid=4294967295
messages-Oct 24 17:34:06 devil kernel: audit(1130168046.056:152): avc: denied { write } for pid=1881 comm="auditd" name="run" dev=md6 ino=32449 scontext=system_u:system_r:auditd_t tcontext=system_u:object_r:file_t tclass=dir
messages-Oct 24 17:34:06 devil kernel: audit(1130168046.056:153): avc: denied { remove_name } for pid=1881 comm="auditd" name="auditd.pid" dev=md6 ino=32468 scontext=system_u:system_r:auditd_t tcontext=system_u:object_r:file_t tclass=dir
messages-Oct 24 17:34:06 devil kernel: audit(1130168046.056:154): avc: denied { unlink } for pid=1881 comm="auditd" name="auditd.pid" dev=md6 ino=32468 scontext=system_u:system_r:auditd_t tcontext=system_u:object_r:file_t tclass=file
messages-Oct 24 17:34:06 devil kernel: audit(1130168046.176:155): avc: denied { search } for pid=9767 comm="auditctl" name="/" dev=md5 ino=2 scontext=system_u:system_r:auditctl_t tcontext=system_u:object_r:file_t tclass=dir
messages-Oct 24 17:34:06 devil kernel: audit(1130168046.176:155): arch=c000003e syscall=21 success=no exit=-2 a0=34ce115820 a1=4 a2=34ce100120 a3=34ce115864 items=1 pid=9767 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 comm="auditctl" exe="/sbin/auditctl"
--
messages-Oct 24 21:01:01 devil crond(pam_unix)[5222]: session opened for user root by (uid=0)
messages-Oct 24 21:01:01 devil crond(pam_unix)[5222]: session closed for user root
messages-Oct 24 21:01:13 devil sshd(pam_unix)[5237]: session opened for user root by root(uid=0)
messages:Oct 24 21:01:46 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages-Oct 24 21:01:46 devil kernel:
messages-Oct 24 21:01:46 devil kernel: Call Trace:<ffffffff8033c3d8>{interruptible_sleep_on_timeout+131}
messages-Oct 24 21:01:46 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff8820a367>{:lockd:lockd_down+207}
messages-Oct 24 21:01:46 devil kernel: <ffffffff88220857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages-Oct 24 21:01:46 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages-Oct 24 21:01:46 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages-Oct 24 21:01:46 devil kernel: <ffffffff8010db02>{tracesys+209}
messages-Oct 24 21:01:57 devil sshd(pam_unix)[5275]: session opened for user root by root(uid=0)
messages-Oct 24 21:26:52 devil kernel: SELinux: initialized (dev 0:19, type nfs), uses genfs_contexts
messages:Oct 24 21:28:02 devil kernel: Badness in interruptible_sleep_on_timeout at kernel/sched.c:3297 (Not tainted)
messages-Oct 24 21:28:02 devil kernel:
messages-Oct 24 21:28:02 devil kernel: Call Trace:<ffffffff8033c3d8>{interruptible_sleep_on_timeout+131}
messages-Oct 24 21:28:02 devil kernel: <ffffffff80131654>{default_wake_function+0} <ffffffff8820a367>{:lockd:lockd_down+207}
messages-Oct 24 21:28:02 devil kernel: <ffffffff88220857>{:nfs:nfs_kill_super+78} <ffffffff80186531>{deactivate_super+95}
messages-Oct 24 21:28:02 devil kernel: <ffffffff8019ca23>{sys_umount+739} <ffffffff801107ea>{syscall_trace_enter+217}
messages-Oct 24 21:28:02 devil kernel: <ffffffff80110827>{syscall_trace_leave+55} <ffffffff8010daa2>{tracesys+113}
messages-Oct 24 21:28:02 devil kernel: <ffffffff8010db02>{tracesys+209}
messages-Oct 24 22:01:01 devil crond(pam_unix)[6513]: session opened for user root by (uid=0)
messages-Oct 24 22:01:01 devil crond(pam_unix)[6513]: session closed for user root
messages-Oct 24 23:01:01 devil crond(pam_unix)[7239]: session opened for user root by (uid=0)
messages-Oct 24 23:01:01 devil crond(pam_unix)[7239]: session closed for user root
messages-Oct 25 00:01:01 devil crond(pam_unix)[7962]: session opened for user root by (uid=0)
messages-Oct 25 00:01:01 devil crond(pam_unix)[7962]: session closed for user root
messages-Oct 25 01:01:01 devil crond(pam_unix)[8686]: session opened for user root by (uid=0)
messages-Oct 25 01:01:01 devil crond(pam_unix)[8686]: session closed for user root
messages-Oct 25 02:01:01 devil crond(pam_unix)[9410]: session opened for user root by (uid=0)
messages-Oct 25 02:01:01 devil crond(pam_unix)[9410]: session closed for user root
messages-Oct 25 03:01:01 devil crond(pam_unix)[10134]: session opened for user root by (uid=0)
messages-Oct 25 03:01:01 devil crond(pam_unix)[10134]: session closed for user root
messages-Oct 25 04:01:01 devil crond(pam_unix)[10858]: session opened for user root by (uid=0)
