Mon Nov 10 15:08:12 2014 baseline_jsonrpc_cmod_hack.prof 29976206 function calls (33600497 primitive calls) in 343.227 CPU seconds Ordered by: internal time List reduced from 2619 to 50 due to restriction <50> ncalls tottime percall cumtime percall filename:lineno(function) 16504/16584 28.458 0.002 89.572 0.005 /usr/lib64/python2.6/threading.py:481(Thread.run) 68344 24.167 0.000 77.159 0.001 /usr/share/vdsm/virt/vm.py:2911(Vm._getRunningVmStats) 9393/2746299 13.395 0.001 0.210 0.000 /usr/share/vdsm/protocoldetector.py:94(MultiProtocolAcceptor._process_events) 81/100 11.606 0.143 67.946 0.679 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 1478844/1478858 9.112 0.000 9.118 0.000 /usr/lib/python2.6/site-packages/vdsm/utils.py:425(convertToStr) 100 7.838 0.078 68.841 0.688 /usr/share/vdsm/virt/sampling.py:452(VmStatsThread.collect) 32923/32924 7.245 0.000 17.585 0.001 /usr/lib64/python2.6/inspect.py:247(getmembers) 68300 5.182 0.000 10.420 0.000 /usr/share/vdsm/virt/vm.py:531(VmStatsThread._getDiskStats) 68344 4.696 0.000 6.360 0.000 /usr/share/vdsm/virt/vm.py:2990(Vm._getGraphicsStats) 806634/806638 4.499 0.000 5.986 0.000 /usr/lib64/python2.6/inspect.py:59(isclass) 614700 4.322 0.000 4.534 0.000 /usr/share/vdsm/virt/sampling.py:355(AdvancedStatsFunction.getStats) 50994/50995 4.315 0.000 29.251 0.001 /usr/lib64/python2.6/asyncore.py:165(poll2) 1278624 4.288 0.000 4.830 0.000 /usr/share/vdsm/virt/vm.py:2859() 155426 3.933 0.000 7.839 0.000 /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) 129861/129962 3.653 0.000 18.206 0.000 /usr/lib64/python2.6/glob.py:18(iglob) 68300 3.617 0.000 8.038 0.000 /usr/share/vdsm/virt/vm.py:556(VmStatsThread._getDiskLatency) 51194/51219 3.500 0.000 3.826 0.000 /usr/lib/python2.6/site-packages/pthread.py:133(Cond.timedwait) 126140 3.224 0.000 3.224 0.000 /usr/share/vdsm/virt/vm.py:559(_avgLatencyCalc) 68300 3.052 0.000 4.061 0.000 /usr/share/vdsm/virt/vm.py:420(VmStatsThread._getBalloonStats) 1269211/1279810 2.860 0.000 3.510 0.000 /usr/share/vdsm/virt/sampling.py:337(AdvancedStatsFunction.interval) 39956 2.759 0.000 19.755 0.000 /usr/share/vdsm/virt/vm.py:2536(Vm._getExtendCandidates) 129420 2.734 0.000 2.894 0.000 /usr/lib64/python2.6/fnmatch.py:45(filter) 129427 2.688 0.000 9.278 0.000 /usr/lib64/python2.6/glob.py:49(glob1) 246178 2.646 0.000 2.651 0.000 /usr/share/vdsm/virt/vm.py:1942(Vm._get_lastStatus) 100 2.487 0.025 64.199 0.642 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) 82778 2.359 0.000 30.025 0.000 /usr/lib64/python2.6/logging/__init__.py:1204(Logger.callHandlers) 146547 2.344 0.000 6.773 0.000 /usr/lib64/python2.6/logging/__init__.py:423(Formatter.format) 68300 2.330 0.000 32.614 0.000 /usr/share/vdsm/virt/vm.py:610(VmStatsThread.get) 17744 2.295 0.000 2.913 0.000 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:211(Parser.buildState) 68482 2.287 0.000 118.418 0.002 /usr/share/vdsm/API.py:355(VM.getStats) 145948 2.270 0.000 17.511 0.000 /usr/lib64/python2.6/logging/__init__.py:764(WatchedFileHandler.emit) 68344 2.202 0.000 7.048 0.000 /usr/share/vdsm/virt/vm.py:2961(Vm._getVmStatus) 388307/388309 2.061 0.000 2.773 0.000 /usr/lib64/python2.6/glob.py:77(has_magic) 986528/986543 2.055 0.000 2.450 0.000 /usr/lib64/python2.6/inspect.py:67(ismethod) 527299 1.987 0.000 4.057 0.000 /usr/lib/python2.6/site-packages/pthread.py:95(Lock.lock) 337600 1.985 0.000 2.513 0.000 /usr/lib64/python2.6/StringIO.py:208(StringIO.write) 88616 1.960 0.000 3.793 0.000 /usr/lib64/python2.6/ConfigParser.py:515(ConfigParser.get) 487484/487486 1.930 0.000 3.289 0.000 /usr/share/vdsm/virt/migration.py:21() 129545 1.929 0.000 2.345 0.000 /usr/lib64/python2.6/posixpath.py:79(split) 84456/84483 1.867 0.000 6.561 0.000 /usr/lib/python2.6/site-packages/pthreading.py:115(Condition.wait) 129402 1.852 0.000 21.437 0.000 /usr/lib/python2.6/site-packages/vdsm/netinfo.py:818(NetInfo) 68263 1.822 0.000 2.424 0.000 /usr/share/vdsm/virt/vm.py:2962(_getVmStatusFromGuest) 527819 1.818 0.000 3.817 0.000 /usr/lib/python2.6/site-packages/pthread.py:98(Lock.unlock) 146049 1.795 0.000 27.666 0.000 /usr/lib64/python2.6/logging/__init__.py:666(WatchedFileHandler.handle) 12638/16487 1.773 0.000 122.876 0.007 /usr/lib/python2.6/site-packages/mom/Monitor.py:53(HostMonitor.collect) 68344 1.768 0.000 92.628 0.001 /usr/share/vdsm/virt/vm.py:2866(Vm.getStats) 73588/76355 1.760 0.000 48.322 0.001 /usr/share/vdsm/virt/sampling.py:345(AdvancedStatsFunction.__call__) 52 1.728 0.033 1.801 0.035 /usr/share/vdsm/API.py:1602(Global._memAvailable) 36544 1.658 0.000 15.448 0.000 /usr/share/vdsm/virt/vm.py:2854(Vm.status) 146547 1.654 0.000 1.864 0.000 /usr/lib64/python2.6/logging/__init__.py:289(LogRecord.getMessage) Ordered by: internal time List reduced from 2619 to 50 due to restriction <50> Function was called by... ncalls tottime cumtime /usr/lib64/python2.6/threading.py:481(Thread.run) <- 1/0 0.002 0.002 /usr/lib/python2.6/site-packages/pthreading.py:102(Condition.__init__) 3/1 0.122 0.122 /usr/lib64/python2.6/genericpath.py:85(_splitext) 1 0.150 0.150 /usr/lib64/python2.6/glob.py:18(iglob) 3/0 0.014 0.014 /usr/lib64/python2.6/logging/__init__.py:148(getLevelName) 33/7 11.268 11.270 /usr/lib64/python2.6/logging/__init__.py:226(LogRecord.__init__) 2 0.002 0.002 /usr/lib64/python2.6/logging/__init__.py:423(Formatter.format) 4/1 9.392 9.395 /usr/lib64/python2.6/logging/__init__.py:1034(Logger.debug) 14/3 15.378 15.378 /usr/lib64/python2.6/logging/__init__.py:1121(Logger.findCaller) 10/0 5.705 5.718 /usr/lib64/python2.6/logging/__init__.py:1155(Logger._log) 1/0 0.303 0.304 /usr/lib64/python2.6/multiprocessing/connection.py:135(Client) 2/0 6.307 6.307 /usr/lib64/python2.6/posixpath.py:42(normcase) 1 0.078 0.078 /usr/lib64/python2.6/posixpath.py:79(split) 4/1 0.035 0.035 /usr/lib64/python2.6/posixpath.py:94(splitext) 4/0 0.304 0.304 /usr/lib64/python2.6/posixpath.py:109(basename) 1/0 0.672 0.672 /usr/lib64/python2.6/site-packages/libvirt.py:286(virEventRunDefaultImpl) 1 0.691 0.691 /usr/lib64/python2.6/threading.py:331(_Semaphore.release) 1/0 0.027 0.027 /usr/lib64/python2.6/threading.py:391(_Event.wait) 1 0.232 0.232 /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) 1 0.411 0.412 /usr/lib64/python2.6/xml/dom/minidom.py:305(_get_elements_by_tagName_helper) 1 0.449 0.449 /usr/lib64/python2.6/xml/dom/minidom.py:349(Attr.__init__) 1 0.058 0.058 /usr/share/vdsm/clientIF.py:146(getInstance) 2/0 0.000 0.230 /usr/share/vdsm/protocoldetector.py:94(MultiProtocolAcceptor._process_events) 1 0.145 0.145 /usr/share/vdsm/virt/sampling.py:337(AdvancedStatsFunction.interval) 1/0 0.005 0.005 /usr/share/vdsm/virt/vm.py:1942(Vm._get_lastStatus) 1 0.058 0.058 /usr/share/vdsm/virt/vm.py:2866(Vm.getStats) 1 0.007 0.007 /usr/share/vdsm/virt/vm.py:2911(Vm._getRunningVmStats) 1 0.078 0.078 /usr/share/vdsm/virt/vm.py:3984(Vm._timeoutExperienced) /usr/share/vdsm/virt/vm.py:2911(Vm._getRunningVmStats) <- 68344 24.167 77.159 /usr/share/vdsm/virt/vm.py:2866(Vm.getStats) /usr/share/vdsm/protocoldetector.py:94(MultiProtocolAcceptor._process_events) <- 2433435/0 16.564 24.448 /usr/lib64/python2.6/threading.py:481(Thread.run) 9394/9393 0.000 0.210 /usr/share/vdsm/protocoldetector.py:77(MultiProtocolAcceptor.serve_forever) 303470/0 0.000 1.725 /usr/share/vdsm/protocoldetector.py:94(MultiProtocolAcceptor._process_events) /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) <- 1 0.414 0.414 /usr/lib/python2.6/site-packages/mom/GuestManager.py:95(GuestManager.interrogate) 2/1 0.000 0.580 /usr/lib/python2.6/site-packages/mom/Monitor.py:17() 5/3 2.070 2.070 /usr/lib/python2.6/site-packages/pthread.py:95(Lock.lock) 3 1.998 1.998 /usr/lib/python2.6/site-packages/pthread.py:98(Lock.unlock) 4 2.466 2.466 /usr/lib/python2.6/site-packages/pthreading.py:66(Lock.acquire) 4/3 1.838 1.838 /usr/lib/python2.6/site-packages/pthreading.py:77(Lock.release) 2/0 0.006 0.006 /usr/lib/python2.6/site-packages/vdsm/utils.py:425(convertToStr) 1 0.005 0.005 /usr/lib64/python2.6/ConfigParser.py:515(ConfigParser.get) 1/0 0.007 0.007 /usr/lib64/python2.6/fnmatch.py:45(filter) 1 0.007 0.007 /usr/lib64/python2.6/glob.py:18(iglob) 2/0 0.010 0.010 /usr/lib64/python2.6/glob.py:77(has_magic) 1 0.001 0.001 /usr/lib64/python2.6/inspect.py:59(isclass) 1 0.004 0.004 /usr/lib64/python2.6/inspect.py:67(ismethod) 1 0.004 0.004 /usr/lib64/python2.6/logging/__init__.py:226(LogRecord.__init__) 1/0 0.005 0.005 /usr/lib64/python2.6/logging/__init__.py:630(WatchedFileHandler.release) 2 0.006 0.006 /usr/lib64/python2.6/logging/__init__.py:764(WatchedFileHandler.emit) 1 0.058 0.058 /usr/lib64/python2.6/threading.py:64(Thread._note) 1 0.524 0.524 /usr/lib64/python2.6/threading.py:310(_Semaphore.acquire) 7/6 4.390 4.390 /usr/lib64/python2.6/threading.py:331(_Semaphore.release) 1 0.634 0.634 /usr/lib64/python2.6/xml/dom/expatbuilder.py:274(ExpatBuilderNS.character_data_handler_cdata) 1 0.181 0.182 /usr/lib64/python2.6/xml/dom/minidom.py:1604(Document.createElement) 1 2.300 2.300 /usr/share/vdsm/protocoldetector.py:94(MultiProtocolAcceptor._process_events) 1 0.147 0.147 /usr/share/vdsm/storage/blockSD.py:146(_getVolsTree) 1 0.051 0.051 /usr/share/vdsm/storage/lvm.py:217(makeLV) 1/0 0.008 0.008 /usr/share/vdsm/virt/guestagent.py:329(GuestAgent.isResponsive) 1/0 0.011 0.011 /usr/share/vdsm/virt/migration.py:21() 1/0 0.005 0.005 /usr/share/vdsm/virt/vm.py:397(VmStatsThread._getCpuStats) 1/0 0.004 0.004 /usr/share/vdsm/virt/vm.py:420(VmStatsThread._getBalloonStats) 1 0.017 0.017 /usr/share/vdsm/virt/vm.py:660(NotifyingVirDomain.__getattr__) 3/0 0.009 0.010 /usr/share/vdsm/virt/vm.py:2911(Vm._getRunningVmStats) 1 0.008 0.008 /usr/share/vdsm/virt/vm.py:2961(Vm._getVmStatus) 1 0.410 0.410 /usr/share/vdsm/virt/vm.py:6011(Element.__hacked_writexml) /usr/lib/python2.6/site-packages/vdsm/utils.py:425(convertToStr) <- 13 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 14/0 0.000 0.000 /usr/lib/python2.6/site-packages/vdsm/utils.py:425(convertToStr) 14 0.000 0.000 /usr/lib64/python2.6/ConfigParser.py:339(ConfigParser.getint) 4 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 1081 0.003 0.003 /usr/share/vdsm/API.py:1276(Global.getStats) 1 0.000 0.000 /usr/share/vdsm/virt/guestagent.py:335(GuestAgent.getGuestInfo) 42 0.000 0.000 /usr/share/vdsm/virt/vm.py:610(VmStatsThread.get) 1409390 8.537 8.543 /usr/share/vdsm/virt/vm.py:2911(Vm._getRunningVmStats) 68299 0.571 0.571 /usr/share/vdsm/virt/vm.py:3009(Vm._getGuestStats) /usr/share/vdsm/virt/sampling.py:452(VmStatsThread.collect) <- 89 6.959 61.578 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) /usr/lib64/python2.6/inspect.py:247(getmembers) <- 1/0 0.001 0.002 /usr/lib64/python2.6/inspect.py:247(getmembers) 32923 7.244 17.585 /usr/share/vdsm/rpc/Bridge.py:159(DynamicBridge._getDefaultArgs) /usr/share/vdsm/virt/vm.py:531(VmStatsThread._getDiskStats) <- 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:397(VmStatsThread._getCpuStats) 68299 5.182 10.420 /usr/share/vdsm/virt/vm.py:610(VmStatsThread.get) /usr/share/vdsm/virt/vm.py:2990(Vm._getGraphicsStats) <- 3 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 2 0.000 0.000 /usr/lib/python2.6/site-packages/vdsm/utils.py:425(convertToStr) 1 0.000 0.000 /usr/lib64/python2.6/ConfigParser.py:339(ConfigParser.getint) 1 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 3 0.000 0.001 /usr/share/vdsm/virt/vm.py:610(VmStatsThread.get) 68334 4.695 6.359 /usr/share/vdsm/virt/vm.py:2911(Vm._getRunningVmStats) /usr/lib64/python2.6/inspect.py:59(isclass) <- 4/0 0.000 0.000 /usr/lib64/python2.6/inspect.py:59(isclass) 806634 4.499 5.986 /usr/lib64/python2.6/inspect.py:247(getmembers) /usr/share/vdsm/virt/sampling.py:355(AdvancedStatsFunction.getStats) <- 1 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 68300 0.960 0.993 /usr/share/vdsm/virt/vm.py:397(VmStatsThread._getCpuStats) 68299 0.860 0.887 /usr/share/vdsm/virt/vm.py:420(VmStatsThread._getBalloonStats) 68300 0.202 0.223 /usr/share/vdsm/virt/vm.py:440(VmStatsThread._getCpuTuneInfo) 68300 0.080 0.098 /usr/share/vdsm/virt/vm.py:460(VmStatsThread._getCpuCount) 68300 0.821 0.844 /usr/share/vdsm/virt/vm.py:474(VmStatsThread._getUserCpuTuneInfo) 68300 0.183 0.207 /usr/share/vdsm/virt/vm.py:512(VmStatsThread._getNetworkStats) 68300 0.153 0.170 /usr/share/vdsm/virt/vm.py:531(VmStatsThread._getDiskStats) 68300 0.889 0.918 /usr/share/vdsm/virt/vm.py:556(VmStatsThread._getDiskLatency) 68300 0.172 0.194 /usr/share/vdsm/virt/vm.py:600(VmStatsThread._getVmJobs) /usr/lib64/python2.6/asyncore.py:165(poll2) <- 50995/50994 4.315 29.251 /usr/lib64/python2.6/asyncore.py:199(loop) /usr/share/vdsm/virt/vm.py:2859() <- 35 0.000 0.000 /usr/share/vdsm/virt/vm.py:1942(Vm._get_lastStatus) 1278589 4.288 4.830 /usr/share/vdsm/virt/vm.py:2854(Vm.status) /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) <- 152719 3.832 7.575 /usr/lib64/python2.6/xml/dom/expatbuilder.py:62(ElementInfo) 2707 0.100 0.264 /usr/lib64/python2.6/xml/dom/expatbuilder.py:350(ExpatBuilderNS.first_element_handler) /usr/lib64/python2.6/glob.py:18(iglob) <- 28 0.000 0.000 /usr/lib/python2.6/site-packages/vdsm/netinfo.py:134(bondOpts) 64 0.000 0.000 /usr/lib/python2.6/site-packages/vdsm/netinfo.py:159(bridgeOpts) 4 0.000 0.001 /usr/lib/python2.6/site-packages/vdsm/netinfo.py:657(getDhclientIfaces) 288 0.000 0.002 /usr/lib/python2.6/site-packages/vdsm/utils.py:388(iteratePids) 129460 3.652 18.195 /usr/lib64/python2.6/glob.py:10(glob) 101/0 0.000 0.004 /usr/lib64/python2.6/glob.py:18(iglob) 15 0.000 0.003 /usr/share/vdsm/storage/fileSD.py:644(getMountsList) 1 0.000 0.003 /usr/share/vdsm/storage/sp.py:1158(StoragePool.__rebuild) 1 0.000 0.001 /usr/share/vdsm/storage/storageServer.py:576(ConnectionAliasRegistrar._iterPersistedConnectionInfo) /usr/share/vdsm/virt/vm.py:556(VmStatsThread._getDiskLatency) <- 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:397(VmStatsThread._getCpuStats) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:531(VmStatsThread._getDiskStats) 68298 3.616 8.038 /usr/share/vdsm/virt/vm.py:610(VmStatsThread.get) /usr/lib/python2.6/site-packages/pthread.py:133(Cond.timedwait) <- 51219/51194 3.500 3.826 /usr/lib/python2.6/site-packages/pthreading.py:115(Condition.wait) /usr/share/vdsm/virt/vm.py:559(_avgLatencyCalc) <- 126140 3.224 3.224 /usr/share/vdsm/virt/vm.py:556(VmStatsThread._getDiskLatency) /usr/share/vdsm/virt/vm.py:420(VmStatsThread._getBalloonStats) <- 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:397(VmStatsThread._getCpuStats) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:531(VmStatsThread._getDiskStats) 68298 3.051 4.061 /usr/share/vdsm/virt/vm.py:610(VmStatsThread.get) /usr/share/vdsm/virt/sampling.py:337(AdvancedStatsFunction.interval) <- 10599/0 0.024 0.024 /usr/share/vdsm/virt/sampling.py:337(AdvancedStatsFunction.interval) 46635 0.104 0.104 /usr/share/vdsm/virt/sampling.py:345(AdvancedStatsFunction.__call__) 1221576 2.732 3.406 /usr/share/vdsm/virt/sampling.py:452(VmStatsThread.collect) 1000 0.001 0.001 /usr/share/vdsm/virt/sampling.py:454() /usr/share/vdsm/virt/vm.py:2536(Vm._getExtendCandidates) <- 39956 2.759 19.755 /usr/share/vdsm/virt/vm.py:2590(Vm.extendDrivesIfNeeded) /usr/lib64/python2.6/fnmatch.py:45(filter) <- 129419 2.734 2.894 /usr/lib64/python2.6/glob.py:49(glob1) /usr/lib64/python2.6/glob.py:49(glob1) <- 1 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 129422 2.688 9.277 /usr/lib64/python2.6/glob.py:18(iglob) 2 0.000 0.000 /usr/lib64/python2.6/glob.py:77(has_magic) 1 0.000 0.000 /usr/lib64/python2.6/posixpath.py:79(split) 1 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) /usr/share/vdsm/virt/vm.py:1942(Vm._get_lastStatus) <- 1 0.000 0.000 /usr/lib/python2.6/site-packages/vdsm/utils.py:818(tobool) 3921 0.016 0.016 /usr/share/vdsm/API.py:1646(Global._countVms) 11 0.000 0.000 /usr/share/vdsm/virt/guestagent.py:115(GuestAgent.__init__) 84 0.001 0.001 /usr/share/vdsm/virt/vm.py:2262(Vm._startUnderlyingVm) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:2345(Vm.preparePaths) 400 0.003 0.003 /usr/share/vdsm/virt/vm.py:2398(Vm._saveStateInternal) 36544 0.241 0.247 /usr/share/vdsm/virt/vm.py:2854(Vm.status) 68344 0.832 0.832 /usr/share/vdsm/virt/vm.py:2866(Vm.getStats) 136769 1.551 1.551 /usr/share/vdsm/virt/vm.py:2961(Vm._getVmStatus) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:3102(Vm._buildCmdLine) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:3191(Vm._getUnderlyingVmDevicesInfo) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:3208(Vm._updateAgentChannels) 85 0.001 0.001 /usr/share/vdsm/virt/vm.py:3251(Vm._domDependentInit) 14 0.000 0.000 /usr/share/vdsm/virt/vm.py:3308(Vm._run) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:4820(Vm._getUnderlyingVmInfo) /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) <- 1 0.159 0.159 /usr/lib/python2.6/site-packages/vdsm/utils.py:818(tobool) 10 2.324 2.324 /usr/lib64/python2.6/socket.py:182(_socketobject.__init__) /usr/lib64/python2.6/logging/__init__.py:1204(Logger.callHandlers) <- 82778 2.359 30.025 /usr/lib64/python2.6/logging/__init__.py:1175(Logger.handle) /usr/lib64/python2.6/logging/__init__.py:423(Formatter.format) <- 146547 2.344 6.773 /usr/lib64/python2.6/logging/__init__.py:643(WatchedFileHandler.format) /usr/share/vdsm/virt/vm.py:610(VmStatsThread.get) <- 68300 2.330 32.614 /usr/share/vdsm/virt/vm.py:2911(Vm._getRunningVmStats) /usr/lib/python2.6/site-packages/mom/Policy/spark.py:211(Parser.buildState) <- 17744 2.295 2.913 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:187(Parser.parse) /usr/share/vdsm/API.py:355(VM.getStats) <- 16291 0.729 36.899 /usr/lib/python2.6/site-packages/mom/HypervisorInterfaces/vdsmInterface.py:87(vdsmInterface.getVmMemoryStats) 16290 0.650 33.798 /usr/lib/python2.6/site-packages/mom/HypervisorInterfaces/vdsmInterface.py:142(vdsmInterface.getVmBalloonInfo) 16292 0.615 32.639 /usr/lib/python2.6/site-packages/mom/HypervisorInterfaces/vdsmInterface.py:166(vdsmInterface.getVmCpuTuneInfo) 3 0.000 0.011 /usr/share/vdsm/API.py:127(VM.__init__) 3921 0.034 2.095 /usr/share/vdsm/API.py:1262(Global.getAllVmStats) 1 0.000 0.003 /usr/share/vdsm/rpc/Bridge.py:124(DynamicBridge._getMethodArgs) 15684 0.258 12.973 /usr/share/vdsm/rpc/Bridge.py:249(DynamicBridge._dynamicMethod) /usr/lib64/python2.6/logging/__init__.py:764(WatchedFileHandler.emit) <- 63170 0.942 9.195 /usr/lib64/python2.6/logging/__init__.py:666(WatchedFileHandler.handle) 82778 1.328 8.317 /usr/lib64/python2.6/logging/__init__.py:851(WatchedFileHandler.emit) /usr/share/vdsm/virt/vm.py:2961(Vm._getVmStatus) <- 68334 2.202 7.046 /usr/share/vdsm/virt/vm.py:2866(Vm.getStats) 10 0.001 0.002 /usr/share/vdsm/virt/vm.py:2911(Vm._getRunningVmStats) /usr/lib64/python2.6/glob.py:77(has_magic) <- 3 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 388301 2.061 2.773 /usr/lib64/python2.6/glob.py:18(iglob) 2/0 0.000 0.000 /usr/lib64/python2.6/glob.py:77(has_magic) 2 0.000 0.000 /usr/lib64/python2.6/posixpath.py:79(split) 1 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) /usr/lib64/python2.6/inspect.py:67(ismethod) <- 15/0 0.000 0.000 /usr/lib64/python2.6/inspect.py:67(ismethod) 966000 2.002 2.385 /usr/lib64/python2.6/inspect.py:247(getmembers) 16405 0.034 0.040 /usr/lib64/python2.6/inspect.py:791(getargspec) 2062 0.009 0.013 /usr/share/vdsm/logUtils.py:27(funcName) 2061 0.010 0.013 /usr/share/vdsm/logUtils.py:55(call2str) /usr/lib/python2.6/site-packages/pthread.py:95(Lock.lock) <- 1 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 527298 1.987 4.057 /usr/lib/python2.6/site-packages/pthreading.py:66(Lock.acquire) /usr/lib64/python2.6/StringIO.py:208(StringIO.write) <- 1 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 87200 0.585 0.725 /usr/lib64/python2.6/codecs.py:347(StreamWriter.write) 85200 0.482 0.616 /usr/lib64/python2.6/xml/dom/minidom.py:299(_write_data) 165199 0.918 1.172 /usr/share/vdsm/virt/vm.py:6011(Element.__hacked_writexml) /usr/lib64/python2.6/ConfigParser.py:515(ConfigParser.get) <- 2 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestManager.py:24(GuestManager) 100 0.002 0.005 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:30(GuestMonitor.__init__) 1 0.000 0.000 /usr/lib/python2.6/site-packages/mom/LogUtils.py:17() 101 0.002 0.005 /usr/lib/python2.6/site-packages/mom/Monitor.py:32(HostMonitor.__init__) 1 0.000 0.000 /usr/lib/python2.6/site-packages/mom/PolicyEngine.py:90(PolicyEngine.get_controllers) 2 0.000 0.000 /usr/lib/python2.6/site-packages/mom/__init__.py:131(MOM._configure_logger) 1 0.000 0.000 /usr/lib/python2.6/site-packages/mom/__init__.py:205(MOM.get_hypervisor_interface) 4 0.000 0.000 /usr/lib/python2.6/site-packages/vdsm/ipwrapper.py:111(Link) 87670 1.946 3.765 /usr/lib64/python2.6/ConfigParser.py:336(ConfigParser._get) 312 0.004 0.007 /usr/lib64/python2.6/ConfigParser.py:348(ConfigParser.getboolean) 100 0.001 0.003 /usr/share/vdsm/API.py:165(VM.create) 1 0.000 0.000 /usr/share/vdsm/caps.py:152(CpuTopology) 2 0.000 0.000 /usr/share/vdsm/caps.py:172(KdumpStatus) 1 0.000 0.000 /usr/share/vdsm/caps.py:549(get) 1 0.000 0.000 /usr/share/vdsm/clientIF.py:65(clientIF.__init__) 1 0.000 0.000 /usr/share/vdsm/clientIF.py:162(clientIF._createSSLContext) 1 0.000 0.000 /usr/share/vdsm/clientIF.py:202(clientIF._prepareMOM) 3 0.000 0.000 /usr/share/vdsm/kaxmlrpclib.py:141(TcpkeepHTTPSConnection) 1 0.000 0.000 /usr/share/vdsm/momIF.py:73(MomThread.setPolicyParameters) 100 0.001 0.002 /usr/share/vdsm/protocoldetector.py:42(MultiProtocolAcceptor) 1 0.000 0.000 /usr/share/vdsm/storage/dispatcher.py:42(Dispatcher.__init__) 1 0.000 0.000 /usr/share/vdsm/storage/hsm.py:178(tryDeprecatedNfsParams) 3 0.000 0.000 /usr/share/vdsm/storage/hsm.py:328(HSM.__init__) 1 0.000 0.000 /usr/share/vdsm/storage/hsm.py:2461(HSM._connectStorageOverIser) 1 0.000 0.000 /usr/share/vdsm/storage/hsm.py:3474(__releaseLocks) 1 0.000 0.000 /usr/share/vdsm/storage/storage_mailbox.py:183(HSM_Mailbox.__init__) 1 0.000 0.000 /usr/share/vdsm/storage/taskManager.py:34(TaskManager.__init__) 1 0.000 0.000 /usr/share/vdsm/storage/threadPool.py:30(ThreadPool.__init__) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:23() 200 0.003 0.005 /usr/share/vdsm/virt/vm.py:1274(Drive.__init__) /usr/share/vdsm/virt/migration.py:21() <- 1 0.000 0.001 /usr/share/vdsm/clientIF.py:21() 2/0 0.000 0.000 /usr/share/vdsm/virt/migration.py:21() 68500 0.055 0.055 /usr/share/vdsm/virt/sampling.py:49(InterfaceSample) /usr/lib64/python2.6/posixpath.py:79(split) <- 1 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 129435 1.928 2.343 /usr/lib64/python2.6/glob.py:18(iglob) 107 0.001 0.001 /usr/lib64/python2.6/os.py:136(makedirs) 2 0.000 0.000 /usr/share/vdsm/storage/hsm.py:2929() /usr/lib/python2.6/site-packages/pthreading.py:115(Condition.wait) <- 23802/23779 0.298 1.330 /usr/lib64/python2.6/Queue.py:150(Queue.get) 2 0.000 0.000 /usr/lib64/python2.6/threading.py:310(_Semaphore.acquire) 60676/60672 1.569 5.231 /usr/lib64/python2.6/threading.py:391(_Event.wait) 1 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 1 0.000 0.000 /usr/share/vdsm/storage/misc.py:868(OperationMutex.acquire) 1 0.000 0.000 /usr/share/vdsm/storage/sdc.py:101(StorageDomainCache._realProduce) /usr/lib/python2.6/site-packages/vdsm/netinfo.py:818(NetInfo) <- 1 0.000 0.000 /usr/lib/python2.6/site-packages/vdsm/netinfo.py:21() 129350 1.852 21.435 /usr/share/vdsm/hooks.py:53(_runHooksDir) 51 0.000 0.002 /usr/share/vdsm/hooks.py:381(_getHookInfo) /usr/share/vdsm/virt/vm.py:2962(_getVmStatusFromGuest) <- 68263 1.822 2.424 /usr/share/vdsm/virt/vm.py:2961(Vm._getVmStatus) /usr/lib/python2.6/site-packages/pthread.py:98(Lock.unlock) <- 3 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 1 0.000 0.000 /usr/lib/python2.6/site-packages/mom/PolicyEngine.py:124(PolicyEngine.run) 527815 1.818 3.817 /usr/lib/python2.6/site-packages/pthreading.py:77(Lock.release) /usr/lib64/python2.6/logging/__init__.py:666(WatchedFileHandler.handle) <- 146049 1.795 27.666 /usr/lib64/python2.6/logging/__init__.py:1204(Logger.callHandlers) /usr/lib/python2.6/site-packages/mom/Monitor.py:53(HostMonitor.collect) <- 162/0 0.019 0.653 /usr/lib/python2.6/site-packages/mom/Collectors/GuestBalloon.py:40(GuestBalloon.collect) 4265/4264 0.000 49.291 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 195 0.035 3.447 /usr/lib/python2.6/site-packages/mom/HostMonitor.py:47(HostMonitor.run) 3686/0 0.625 26.748 /usr/lib/python2.6/site-packages/mom/Monitor.py:53(HostMonitor.collect) 161 0.029 1.175 /usr/lib64/python2.6/logging/__init__.py:1046(Logger.info) /usr/share/vdsm/virt/vm.py:2866(Vm.getStats) <- 68340 1.768 92.617 /usr/share/vdsm/API.py:355(VM.getStats) 4 0.000 0.011 /usr/share/vdsm/hooks.py:342(before_get_vm_stats) /usr/share/vdsm/virt/sampling.py:345(AdvancedStatsFunction.__call__) <- 628 0.012 0.417 /usr/share/vdsm/virt/sampling.py:337(AdvancedStatsFunction.interval) 2767/0 0.051 1.700 /usr/share/vdsm/virt/sampling.py:345(AdvancedStatsFunction.__call__) 72960 1.698 47.904 /usr/share/vdsm/virt/sampling.py:452(VmStatsThread.collect) /usr/share/vdsm/API.py:1602(Global._memAvailable) <- 52 1.728 1.801 /usr/share/vdsm/API.py:1276(Global.getStats) /usr/share/vdsm/virt/vm.py:2854(Vm.status) <- 50 0.003 0.027 /usr/lib64/python2.6/logging/__init__.py:1034(Logger.debug) 36044 1.634 15.221 /usr/share/vdsm/API.py:1348(reportedStatus) 50 0.003 0.025 /usr/share/vdsm/clientIF.py:387(clientIF.createVm) 400 0.018 0.175 /usr/share/vdsm/virt/vm.py:2398(Vm._saveStateInternal) /usr/lib64/python2.6/logging/__init__.py:289(LogRecord.getMessage) <- 146547 1.654 1.864 /usr/lib64/python2.6/logging/__init__.py:423(Formatter.format)