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

I found the poll in virEventPollRunOnce (vireventpoll.c), a POLLHUP event occurred for a pipe descriptor, thus the poll always returned because of it.( the pipe broken). this may be the best I could track temporarily，I don't know what to do netx , to mask the hangup event or to do something on the event, if I should handle the hangup event , what to do ? Is it a bug or something ? any help would be appreciate. :) 2013/10/31 jj <menghuizhanguo@gmail.com>

why the poll always got 1 event ... ? any suggestion ?

"virEventPollRunOnce:638 : Poll got 1 event(s)" ------------------ Original ------------------ *From: * "menghuizhanguo";<menghuizhanguo@gmail.com>; *Date: * Thu, Oct 31, 2013 08:43 AM *To: * "libvirt-users"<libvirt-users@redhat.com>; ** *Subject: * Re: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 .....

I put a complete debug log here https://bugzilla.redhat.com/show_bug.cgi?id=1005570 (attement) I created two mail threads , sorry . ====== I put some debug info here https://bugzilla.redhat.com/show_bug.cgi?id=1005570 It seems something happened in virLXCControllerConsoleUpdateWatch function, a pipe , the write side broken, so the read side hangup which causes 100% cpu usage. On my compute , libvirt_lxc started, 3 hours laster, this would occur . In early time , under normal situation , the debug would print informations on newHostEvents or newContEvents , but then It's going to be crazy ... no debug information on newHostEvents or newContEvents anymore ,just infinate loop. I even could not understand what's the meaning of the code ~~ ------------------ Original ------------------ From: "Daniel P. Berrange";<berrange@redhat.com>; Date: Fri, Nov 1, 2013 06:12 PM To: "jj"<menghuizhanguo@gmail.com>; Cc: "libvirt-users"<libvirt-users@redhat.com>; Subject: 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 :| _______________________________________________ libvirt-users mailing list libvirt-users@redhat.com https://www.redhat.com/mailman/listinfo/libvirt-users ..