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

Wednesday, 30 October 2013

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:  &quot;menghuizhanguo&quot;;&lt;menghuizhanguo(a)gmail.com&gt;;
Date:  Thu, Oct 24, 2013 03:47 PM
To:  &quot;libvirt-users&quot;&lt;libvirt-users(a)redhat.com&gt;; 

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 
 .....

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

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