thr3ads.net - libvirt users - Re: [libvirt-users] libvirt

If this information is useful, please help other people find it:
Share via:

jj zhong

2013-Oct-24 07:47 UTC

[libvirt-users] libvirt_lxc causes cpu 100% usage

libvirt_lxc causes cpu 100% usage?
libvirt version : 1.1.1
I don't know what happend, any help would be appreciated.

thx


top - 07:39:44 up 1 day,  2:13,  2 users,  load average: 4.72, 4.59, 4.51
Tasks: 243 total,   4 running, 239 sleeping,   0 stopped,   0 zombie
%Cpu0  : 33.1 us,  1.3 sy,  0.0 ni, 64.6 id,  0.7 wa,  0.0 hi,  0.3 si,
0.0 st
%Cpu1  : 41.1 us,  3.0 sy,  0.0 ni, 55.3 id,  0.7 wa,  0.0 hi,  0.0 si,
0.0 st
%Cpu2  : 18.0 us,  2.0 sy,  0.0 ni, 79.7 id,  0.0 wa,  0.0 hi,  0.3 si,
0.0 st
%Cpu3  : 94.8 us,  5.2 sy,  0.0 ni,  0.0 id,  0.0 wa,  0.0 hi,  0.0 si,
0.0 st
%Cpu4  : 55.7 us,  7.5 sy,  0.0 ni, 36.7 id,  0.0 wa,  0.0 hi,  0.0 si,
0.0 st
%Cpu5  : 66.1 us,  4.3 sy,  0.0 ni, 29.6 id,  0.0 wa,  0.0 hi,  0.0 si,
0.0 st
%Cpu6  : 16.8 us,  1.0 sy,  0.0 ni, 82.2 id,  0.0 wa,  0.0 hi,  0.0 si,
0.0 st
%Cpu7  :  7.2 us,  0.7 sy,  0.0 ni, 92.1 id,  0.0 wa,  0.0 hi,  0.0 si,
0.0 st
KiB Mem:   1972112 total,  1894912 used,    77200 free,   236576 buffers
KiB Swap: 10485744 total,        0 used, 10485744 free,   574544 cached

  PID USER      PR  NI  VIRT  RES  SHR S  %CPU %MEM    TIME+
COMMAND
15750 root      20   0 10448 2080 1216 R 100.3  0.1   1322:38
libvirt_lxc
27517 root      20   0 10448 2032 1200 R  99.3  0.1   1273:44
libvirt_lxc
25250 root      20   0 10480 2048 1200 R  99.0  0.1   1277:31 libvirt_lxc
....

2013-Oct-30 02:50 UTC

head link

Re: [libvirt-users] libvirt_lxc causes cpu 100% usage

log_level = 1
In the lxc log , under normal situation , about 6~7 minutes between loops . the
log below is printed in a loop.（the minimal loop content)

2013-10-29 10:17:00.567+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.567+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 7
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
2013-10-29 10:17:00.568+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=7 fd=12 events=1 fromHost=0 fromcont=0
2013-10-29 10:17:00.568+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=3
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.568+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=8 timeout=-1
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 2
event(s)
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 8
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=10
2013-10-29 10:17:00.570+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=10 fromHost=0 fromcont=60
2013-10-29 10:17:00.570+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-29 10:17:00.570+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=0
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=0
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
2013-10-29 10:17:00.570+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=7 fd=12 events=1 fromHost=0 fromcont=0
2013-10-29 10:17:00.570+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=3
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.571+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=8 timeout=-1
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 8
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=10
2013-10-29 10:17:00.572+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=10 fromHost=0 fromcont=60
2013-10-29 10:17:00.572+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-29 10:17:00.572+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=0
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=0
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.572+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=0 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=7 timeout=-1
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 7
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
2013-10-29 10:17:00.574+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=7 fd=12 events=1 fromHost=0 fromcont=0
2013-10-29 10:17:00.574+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=3
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.574+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=8 timeout=-1
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 8
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=10
2013-10-29 10:17:00.575+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=10 fromHost=0 fromcont=60
2013-10-29 10:17:00.575+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-29 10:17:00.575+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=0
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=0
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.576+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=0 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=7 timeout=-1
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 7
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
2013-10-29 10:17:00.577+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=7 fd=12 events=1 fromHost=0 fromcont=0
2013-10-29 10:17:00.577+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=3
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.580+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=8 timeout=-1
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 2
event(s)
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 8
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=10
2013-10-29 10:17:00.581+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=10 fromHost=0 fromcont=60
2013-10-29 10:17:00.581+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-29 10:17:00.581+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=0
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=0
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
2013-10-29 10:17:00.582+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=7 fd=12 events=1 fromHost=0 fromcont=0
2013-10-29 10:17:00.582+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=3
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.582+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=8 timeout=-1
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 8
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=10
2013-10-29 10:17:00.583+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=10 fromHost=0 fromcont=60
2013-10-29 10:17:00.583+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-29 10:17:00.583+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=0
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.584+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=0 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=7 timeout=-1


under abnormal situation , the below log generated without time delay  between
loops.

2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 7
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=8
2013-10-30 00:31:57.470+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=8 fromHost=0 fromcont=1024
2013-10-30 00:31:57.470+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-30 00:31:57.470+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=0 host watch 6=3
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=0
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-30 00:31:57.471+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=0 d=0
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=7 timeout=-1


To gaofeng, 
If you think the log I paste above losts something import , tell me.


------------------ Original ------------------
From:  "menghuizhanguo";<menghuizhanguo@gmail.com>;
Date:  Thu, Oct 24, 2013 03:47 PM
To:  "libvirt-users"<libvirt-users@redhat.com>; 

Subject:  libvirt_lxc causes cpu 100% usage



libvirt_lxc causes cpu 100% usage?
libvirt version : 1.1.1
I don't know what happend, any help would be appreciated.

thx


top - 07:39:44 up 1 day,  2:13,  2 users,  load average: 4.72, 4.59, 4.51
 Tasks: 243 total,   4 running, 239 sleeping,   0 stopped,   0 zombie
%Cpu0  : 33.1 us,  1.3 sy,  0.0 ni, 64.6 id,  0.7 wa,  0.0 hi,  0.3 si,  0.0 st
%Cpu1  : 41.1 us,  3.0 sy,  0.0 ni, 55.3 id,  0.7 wa,  0.0 hi,  0.0 si,  0.0 st
 %Cpu2  : 18.0 us,  2.0 sy,  0.0 ni, 79.7 id,  0.0 wa,  0.0 hi,  0.3 si,  0.0 st
%Cpu3  : 94.8 us,  5.2 sy,  0.0 ni,  0.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
%Cpu4  : 55.7 us,  7.5 sy,  0.0 ni, 36.7 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
 %Cpu5  : 66.1 us,  4.3 sy,  0.0 ni, 29.6 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
%Cpu6  : 16.8 us,  1.0 sy,  0.0 ni, 82.2 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
%Cpu7  :  7.2 us,  0.7 sy,  0.0 ni, 92.1 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
 KiB Mem:   1972112 total,  1894912 used,    77200 free,   236576 buffers
KiB Swap: 10485744 total,        0 used, 10485744 free,   574544 cached

  PID USER      PR  NI  VIRT  RES  SHR S  %CPU %MEM    TIME+  COMMAND           
 15750 root      20   0 10448 2080 1216 R 100.3  0.1   1322:38 libvirt_lxc
27517 root      20   0 10448 2032 1200 R  99.3  0.1   1273:44 libvirt_lxc       
25250 root      20   0 10480 2048 1200 R  99.0  0.1   1277:31 libvirt_lxc 
 .....

2013-Oct-31 00:43 UTC

head link

Re: [libvirt-users] libvirt_lxc causes cpu 100% usage

I consider this's a problem on mips platform, while it did not happened on
x86 after testing.   :-( ...





------------------ Original ------------------
From:  "menghuizhanguo";<menghuizhanguo@gmail.com>;
Date:  Wed, Oct 30, 2013 10:50 AM
To:  "menghuizhanguo"<menghuizhanguo@gmail.com>;
"libvirt-users"<libvirt-users@redhat.com>;
Cc:  "Gao feng"<gaofeng@cn.fujitsu.com>; 
Subject:  Re:libvirt_lxc causes cpu 100% usage



log_level = 1
In the lxc log , under normal situation , about 6~7 minutes between loops . the
log below is printed in a loop.（the minimal loop content)

2013-10-29 10:17:00.567+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.567+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 7
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
2013-10-29 10:17:00.568+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=7 fd=12 events=1 fromHost=0 fromcont=0
2013-10-29 10:17:00.568+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=3
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.568+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.568+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=8 timeout=-1
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 2
event(s)
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 8
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.569+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=10
2013-10-29 10:17:00.570+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=10 fromHost=0 fromcont=60
2013-10-29 10:17:00.570+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-29 10:17:00.570+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=0
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=0
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
2013-10-29 10:17:00.570+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=7 fd=12 events=1 fromHost=0 fromcont=0
2013-10-29 10:17:00.570+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=3
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-29 10:17:00.570+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.571+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=8 timeout=-1
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.571+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 8
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=10
2013-10-29 10:17:00.572+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=10 fromHost=0 fromcont=60
2013-10-29 10:17:00.572+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-29 10:17:00.572+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=0
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=0
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.572+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.572+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=0 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=7 timeout=-1
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 7
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.573+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
2013-10-29 10:17:00.574+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=7 fd=12 events=1 fromHost=0 fromcont=0
2013-10-29 10:17:00.574+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=3
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.574+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.574+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=8 timeout=-1
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 8
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=10
2013-10-29 10:17:00.575+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=10 fromHost=0 fromcont=60
2013-10-29 10:17:00.575+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-29 10:17:00.575+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=0
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=0
2013-10-29 10:17:00.575+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.576+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=0 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=7 timeout=-1
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.576+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 7
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.577+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
2013-10-29 10:17:00.577+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=7 fd=12 events=1 fromHost=0 fromcont=0
2013-10-29 10:17:00.577+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=3
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.580+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.580+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=8 timeout=-1
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 2
event(s)
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 8
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=10
2013-10-29 10:17:00.581+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=10 fromHost=0 fromcont=60
2013-10-29 10:17:00.581+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-29 10:17:00.581+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=0
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=0
2013-10-29 10:17:00.581+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
2013-10-29 10:17:00.582+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=7 fd=12 events=1 fromHost=0 fromcont=0
2013-10-29 10:17:00.582+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=3
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.582+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.582+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=8 timeout=-1
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 8
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=10
2013-10-29 10:17:00.583+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=10 fromHost=0 fromcont=60
2013-10-29 10:17:00.583+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-29 10:17:00.583+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=1 host watch 6=0
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=1
2013-10-29 10:17:00.583+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=6 w=7
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.584+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=0 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-29 10:17:00.584+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=7 timeout=-1


under abnormal situation , the below log generated without time delay  between
loops.

2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollRunOnce:638 : Poll got 1
event(s)
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchTimeouts:423 :
Dispatch 2
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:468 :
Dispatch 7
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=0 w=1
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=1 w=2
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=2 w=3
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=3 w=4
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=4 w=5
2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=5 w=6
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollDispatchHandles:496 :
EVENT_POLL_DISPATCH_HANDLE: watch=6 events=8
2013-10-30 00:31:57.470+0000: 7566: debug : virLXCControllerConsoleIO:974 : IO
event watch=6 fd=18 events=8 fromHost=0 fromcont=1024
2013-10-30 00:31:57.470+0000: 7566: debug : virLXCControllerConsoleIO:1044 : Got
EOF on 6 18
2013-10-30 00:31:57.470+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=0 host watch 6=3
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=0
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollInterruptLocked:710 :
Skip interrupt, 1 1996885056
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollDispatchHandles:482 :
i=7 w=8
2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-30 00:31:57.471+0000: 7566: debug : virEventRunDefaultImpl:270 : running
default event implementation
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollCleanupTimeouts:514 :
Cleanup 2
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollCleanupHandles:562 :
Cleanup 8
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=0 d=0
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollCalculateTimeout:332 :
Calculate expiry of 2 timers
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollCalculateTimeout:361 :
Timeout at 0 due in -1 ms
2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=7 timeout=-1


To gaofeng, 
If you think the log I paste above losts something import , tell me.


------------------ Original ------------------
From:  "menghuizhanguo";<menghuizhanguo@gmail.com>;
Date:  Thu, Oct 24, 2013 03:47 PM
To:  "libvirt-users"<libvirt-users@redhat.com>; 

Subject:  libvirt_lxc causes cpu 100% usage



libvirt_lxc causes cpu 100% usage?
libvirt version : 1.1.1
I don't know what happend, any help would be appreciated.

thx


top - 07:39:44 up 1 day,  2:13,  2 users,  load average: 4.72, 4.59, 4.51
 Tasks: 243 total,   4 running, 239 sleeping,   0 stopped,   0 zombie
%Cpu0  : 33.1 us,  1.3 sy,  0.0 ni, 64.6 id,  0.7 wa,  0.0 hi,  0.3 si,  0.0 st
%Cpu1  : 41.1 us,  3.0 sy,  0.0 ni, 55.3 id,  0.7 wa,  0.0 hi,  0.0 si,  0.0 st
 %Cpu2  : 18.0 us,  2.0 sy,  0.0 ni, 79.7 id,  0.0 wa,  0.0 hi,  0.3 si,  0.0 st
%Cpu3  : 94.8 us,  5.2 sy,  0.0 ni,  0.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
%Cpu4  : 55.7 us,  7.5 sy,  0.0 ni, 36.7 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
 %Cpu5  : 66.1 us,  4.3 sy,  0.0 ni, 29.6 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
%Cpu6  : 16.8 us,  1.0 sy,  0.0 ni, 82.2 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
%Cpu7  :  7.2 us,  0.7 sy,  0.0 ni, 92.1 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st
 KiB Mem:   1972112 total,  1894912 used,    77200 free,   236576 buffers
KiB Swap: 10485744 total,        0 used, 10485744 free,   574544 cached

  PID USER      PR  NI  VIRT  RES  SHR S  %CPU %MEM    TIME+  COMMAND           
 15750 root      20   0 10448 2080 1216 R 100.3  0.1   1322:38 libvirt_lxc
27517 root      20   0 10448 2032 1200 R  99.3  0.1   1273:44 libvirt_lxc       
25250 root      20   0 10480 2048 1200 R  99.0  0.1   1277:31 libvirt_lxc 
 .....

Daniel P. Berrange

2013-Nov-01 10:12 UTC

head link

Re: [libvirt-users] libvirt_lxc causes cpu 100% usage

On Wed, Oct 30, 2013 at 10:50:07AM +0800, jj wrote:> log_level = 1
> 
> under abnormal situation , the below log generated without time delay 
between loops.
> 
> 2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollRunOnce:638 : Poll
got 1 event(s)
> 2013-10-30 00:31:57.469+0000: 7566: debug :
virEventPollDispatchTimeouts:423 : Dispatch 2
> 2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:468
: Dispatch 7
> 2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482
: i=0 w=1
> 2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482
: i=1 w=2
> 2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482
: i=2 w=3
> 2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482
: i=3 w=4
> 2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482
: i=4 w=5
> 2013-10-30 00:31:57.469+0000: 7566: debug : virEventPollDispatchHandles:482
: i=5 w=6
> 2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollDispatchHandles:496
: EVENT_POLL_DISPATCH_HANDLE: watch=6 events=8
> 2013-10-30 00:31:57.470+0000: 7566: debug : virLXCControllerConsoleIO:974 :
IO event watch=6 fd=18 events=8 fromHost=0 fromcont=1024
> 2013-10-30 00:31:57.470+0000: 7566: debug : virLXCControllerConsoleIO:1044
: Got EOF on 6 18
> 2013-10-30 00:31:57.470+0000: 7566: debug :
virLXCControllerConsoleUpdateWatch:837 : Container watch 7=0 host watch 6=3
> 2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=7 events=0
> 2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollInterruptLocked:710
: Skip interrupt, 1 1996885056
> 2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollUpdateHandle:147 :
EVENT_POLL_UPDATE_HANDLE: watch=6 events=3
> 2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollInterruptLocked:710
: Skip interrupt, 1 1996885056
> 2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollDispatchHandles:482
: i=7 w=8
> 2013-10-30 00:31:57.470+0000: 7566: debug : virEventPollCleanupTimeouts:514
: Cleanup 2
> 2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollCleanupHandles:562
: Cleanup 8
> 2013-10-30 00:31:57.471+0000: 7566: debug : virEventRunDefaultImpl:270 :
running default event implementation
> 2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollCleanupTimeouts:514
: Cleanup 2
> 2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollCleanupHandles:562
: Cleanup 8
> 2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=0 w=1, f=3 e=1 d=0
> 2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=1 w=2, f=5 e=1 d=0
> 2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=2 w=3, f=7 e=1 d=0
> 2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=3 w=4, f=9 e=1 d=0
> 2013-10-30 00:31:57.471+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=4 w=5, f=13 e=1 d=0
> 2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=5 w=6, f=18 e=5 d=0
> 2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=6 w=7, f=12 e=0 d=0
> 2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollMakePollFDs:391 :
Prepare n=7 w=8, f=14 e=1 d=0
> 2013-10-30 00:31:57.472+0000: 7566: debug :
virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
> 2013-10-30 00:31:57.472+0000: 7566: debug :
virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
> 2013-10-30 00:31:57.472+0000: 7566: debug : virEventPollRunOnce:627 :
EVENT_POLL_RUN: nhandles=7 timeout=-1

Can you provide a more complete log file for this scenario. I'd like
to see multiple cases of the virLXCControllerConsoleIO log message,
preferrably from right when the container first starts up. If the
logfile is very large, upload it somewhere rather than sending to
the list.

Also, when does this occur ? Does it happen immediately when you
start an LXC guest on mips ? Or does it only happen afer the VM
has been running for some period of time  ?


Daniel
-- 
|: http://berrange.com      -o-    http://www.flickr.com/photos/dberrange/ :|
|: http://libvirt.org              -o-             http://virt-manager.org :|
|: http://autobuild.org       -o-         http://search.cpan.org/~danberr/ :|
|: http://entangle-photo.org       -o-       http://live.gnome.org/gtk-vnc :|

Apparently Analagous Threads

Search for more possibly parallel threads

libvirt users - Oct 2013 - Re: libvirt_lxc causes cpu 100% usage

[libvirt-users] libvirt_lxc causes cpu 100% usage

Re: [libvirt-users] libvirt_lxc causes cpu 100% usage

Re: [libvirt-users] libvirt_lxc causes cpu 100% usage

Re: [libvirt-users] libvirt_lxc causes cpu 100% usage

Apparently Analagous Threads