Re: libvirt_lxc causes cpu 100% usage

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



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

 "virEventPollRunOnce:638 : Poll got 1 event(s)"
------------------ Original ------------------
From:  "menghuizhanguo";<menghuizhanguo@xxxxxxxxx>;
Date:  Thu, Oct 31, 2013 08:43 AM
To:  "libvirt-users"<libvirt-users@xxxxxxxxxx>;
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@xxxxxxxxx>;
Date:  Wed, Oct 30, 2013 10:50 AM
To:  "menghuizhanguo"<menghuizhanguo@xxxxxxxxx>; "libvirt-users"<libvirt-users@xxxxxxxxxx>;
Cc:  "Gao feng"<gaofeng@xxxxxxxxxxxxxx>;
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@xxxxxxxxx>;
Date:  Thu, Oct 24, 2013 03:47 PM
To:  "libvirt-users"<libvirt-users@xxxxxxxxxx>;
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
.....
_______________________________________________
libvirt-users mailing list
libvirt-users@xxxxxxxxxx
https://www.redhat.com/mailman/listinfo/libvirt-users

[Index of Archives]     [Virt Tools]     [Lib OS Info]     [Fedora Users]     [Fedora Desktop]     [Fedora SELinux]     [Yosemite News]     [KDE Users]

  Powered by Linux