Mon Nov 10 15:08:12 2014 baseline_jsonrpc_cmod.prof 50417851 function calls (64835240 primitive calls) in 651.791 CPU seconds Ordered by: internal time List reduced from 2632 to 50 due to restriction <50> ncalls tottime percall cumtime percall filename:lineno(function) 98/100 171.140 1.746 240.428 2.404 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 97345/12686631 70.419 0.001 170.315 0.000 /usr/lib64/python2.6/copy.py:144(deepcopy) 13636/13711 39.225 0.003 189.352 0.014 /usr/lib64/python2.6/threading.py:481(Thread.run) 10429451 28.526 0.000 40.548 0.000 /usr/lib64/python2.6/copy.py:261(_keep_alive) 96639/1122019 23.297 0.000 168.826 0.000 /usr/lib64/python2.6/copy.py:251(_deepcopy_dict) 65621 22.852 0.000 75.526 0.001 /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) 2566920/2566921 18.205 0.000 26.546 0.000 /usr/share/vdsm/protocoldetector.py:94(MultiProtocolAcceptor._process_events) 100 9.077 0.091 73.731 0.737 /usr/share/vdsm/virt/sampling.py:452(VmStatsThread.collect) 1420035 8.615 0.000 8.615 0.000 /usr/lib/python2.6/site-packages/vdsm/utils.py:424(convertToStr) 27178 6.568 0.000 15.722 0.001 /usr/lib64/python2.6/inspect.py:247(getmembers) 9245239 5.549 0.000 5.549 0.000 /usr/lib64/python2.6/copy.py:197(_deepcopy_atomic) 155426 5.546 0.000 10.888 0.000 /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) 65581 4.972 0.000 9.881 0.000 /usr/share/vdsm/virt/vm.py:532(VmStatsThread._getDiskStats) 590229 4.847 0.000 5.056 0.000 /usr/share/vdsm/virt/sampling.py:355(AdvancedStatsFunction.getStats) 65621 4.420 0.000 5.993 0.000 /usr/share/vdsm/virt/vm.py:2991(Vm._getGraphicsStats) 17897 3.994 0.000 4.767 0.000 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:211(Parser.buildState) 679450 3.976 0.000 5.225 0.000 /usr/lib64/python2.6/inspect.py:59(isclass) 42357/42358 3.938 0.000 26.644 0.001 /usr/lib64/python2.6/asyncore.py:165(poll2) 125807/125906 3.619 0.000 17.444 0.000 /usr/lib64/python2.6/glob.py:18(iglob) 52204/52229 3.551 0.000 3.906 0.000 /usr/lib/python2.6/site-packages/pthread.py:133(Cond.timedwait) 65581 3.450 0.000 7.770 0.000 /usr/share/vdsm/virt/vm.py:557(VmStatsThread._getDiskLatency) 1291984/1302760 3.416 0.000 4.042 0.000 /usr/share/vdsm/virt/sampling.py:337(AdvancedStatsFunction.interval) 99/100 3.225 0.033 65.560 0.656 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) 121138 3.154 0.000 3.154 0.000 /usr/share/vdsm/virt/vm.py:560(_avgLatencyCalc) 40701 2.970 0.000 19.818 0.000 /usr/share/vdsm/virt/vm.py:2537(Vm._getExtendCandidates) 65581 2.931 0.000 3.905 0.000 /usr/share/vdsm/virt/vm.py:421(VmStatsThread._getBalloonStats) 1090348 2.886 0.000 3.348 0.000 /usr/share/vdsm/virt/vm.py:2860() 16307/16764 2.885 0.000 120.868 0.007 /usr/lib/python2.6/site-packages/mom/Monitor.py:53(HostMonitor.collect) 125372 2.611 0.000 2.753 0.000 /usr/lib64/python2.6/fnmatch.py:45(filter) 125379 2.556 0.000 8.980 0.000 /usr/lib64/python2.6/glob.py:49(glob1) 74577 2.423 0.000 29.220 0.000 /usr/lib64/python2.6/logging/__init__.py:1204(Logger.callHandlers) 231941 2.375 0.000 2.375 0.000 /usr/share/vdsm/virt/vm.py:1943(Vm._get_lastStatus) 294217 2.366 0.000 2.669 0.000 /usr/lib64/python2.6/xml/dom/minidom.py:349(Attr.__init__) 337594/337600 2.345 0.000 3.316 0.000 /usr/lib64/python2.6/StringIO.py:208(StringIO.write) 130142 2.333 0.000 6.620 0.000 /usr/lib64/python2.6/logging/__init__.py:423(Formatter.format) 65581 2.323 0.000 33.361 0.001 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) 129531 2.260 0.000 16.839 0.000 /usr/lib64/python2.6/logging/__init__.py:764(WatchedFileHandler.emit) 65768 2.206 0.000 114.833 0.002 /usr/share/vdsm/API.py:356(VM.getStats) 22680 2.125 0.000 2.428 0.000 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:103(Parser.addRule) 10767/590832 2.096 0.000 2.713 0.000 /usr/lib64/python2.6/xml/dom/minidom.py:305(_get_elements_by_tagName_helper) 482516 2.078 0.000 6.366 0.000 /usr/lib/python2.6/site-packages/pthreading.py:66(Lock.acquire) 79723/79750 2.066 0.000 6.702 0.000 /usr/lib/python2.6/site-packages/pthreading.py:115(Condition.wait) 65621 2.052 0.000 6.577 0.000 /usr/share/vdsm/virt/vm.py:2962(Vm._getVmStatus) 814324/814373 2.047 0.000 2.371 0.000 /usr/lib64/python2.6/inspect.py:67(ismethod) 376165 1.946 0.000 2.630 0.000 /usr/lib64/python2.6/glob.py:77(has_magic) 125497 1.889 0.000 2.210 0.000 /usr/lib64/python2.6/posixpath.py:79(split) 380180 1.851 0.000 1.976 0.000 /usr/lib64/python2.6/xml/dom/minidom.py:281(Document._append_child) 468465/468468 1.833 0.000 3.120 0.000 /usr/share/vdsm/virt/migration.py:21() 129632/129633 1.791 0.000 26.770 0.000 /usr/lib64/python2.6/logging/__init__.py:666(WatchedFileHandler.handle) 125354 1.764 0.000 20.564 0.000 /usr/lib/python2.6/site-packages/vdsm/netinfo.py:818(NetInfo) Ordered by: internal time List reduced from 2632 to 50 due to restriction <50> Function was called by... ncalls tottime cumtime /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) <- 2 14.448 14.448 /usr/lib/python2.6/site-packages/mom/Monitor.py:17() 3 19.593 19.593 /usr/lib/python2.6/site-packages/pthread.py:95(Lock.lock) 8 38.456 38.456 /usr/lib/python2.6/site-packages/pthread.py:98(Lock.unlock) 1 0.303 0.303 /usr/lib/python2.6/site-packages/pthreading.py:66(Lock.acquire) 1 9.549 9.549 /usr/lib/python2.6/site-packages/pthreading.py:77(Lock.release) 3 1.929 1.929 /usr/lib64/python2.6/copy.py:144(deepcopy) 1 0.480 0.480 /usr/lib64/python2.6/copy.py:261(_keep_alive) 1 0.004 0.005 /usr/lib64/python2.6/inspect.py:247(getmembers) 3 19.715 19.715 /usr/lib64/python2.6/threading.py:64(Thread._note) 5 12.093 12.093 /usr/lib64/python2.6/threading.py:310(_Semaphore.acquire) 6 53.925 53.925 /usr/lib64/python2.6/threading.py:331(_Semaphore.release) 1 0.703 0.714 /usr/lib64/python2.6/xml/dom/expatbuilder.py:867(InternalSubsetExtractor) 1 1.007 1.007 /usr/lib64/python2.6/xml/dom/minidom.py:299(_write_data) 1 0.266 0.266 /usr/lib64/python2.6/xml/dom/minidom.py:305(_get_elements_by_tagName_helper) 1 0.196 0.196 /usr/lib64/python2.6/xml/dom/minidom.py:349(Attr.__init__) 1 0.151 0.151 /usr/lib64/python2.6/xml/dom/minidom.py:1361(DOMImplementation.createDocument) 1 0.109 0.116 /usr/share/vdsm/storage/lvm.py:411(LVMCache._reloadlvs) 1 0.102 0.102 /usr/share/vdsm/storage/lvm.py:446() 1 0.007 0.007 /usr/share/vdsm/virt/vm.py:2962(Vm._getVmStatus) /usr/lib64/python2.6/copy.py:144(deepcopy) <- 475/0 0.013 0.128 /usr/lib64/python2.6/copy.py:144(deepcopy) 403568/0 2.934 132.089 /usr/lib64/python2.6/copy.py:224(_deepcopy_list) 12185185/0 66.657 164.471 /usr/lib64/python2.6/copy.py:251(_deepcopy_dict) 58/0 0.001 0.006 /usr/lib64/python2.6/threading.py:481(Thread.run) 100 0.003 0.031 /usr/share/vdsm/API.py:328(VM.destroy) 65581 0.503 1.230 /usr/share/vdsm/virt/vm.py:601(VmStatsThread._getVmJobs) 100 0.003 0.589 /usr/share/vdsm/virt/vm.py:2026(Vm.buildConfDevices) 397 0.004 0.010 /usr/share/vdsm/virt/vm.py:2399(Vm._saveStateInternal) 31167 0.299 168.455 /usr/share/vdsm/virt/vm.py:2855(Vm.status) /usr/lib64/python2.6/threading.py:481(Thread.run) <- 1 0.090 0.090 /usr/lib/python2.6/site-packages/pthread.py:98(Lock.unlock) 1 1.916 1.916 /usr/lib/python2.6/site-packages/vdsm/utils.py:290(_parseMemInfo) 1/0 0.335 0.335 /usr/lib/python2.6/site-packages/yajsonrpc/betterAsyncore.py:252(Dispatcher.__invoke) 1 0.164 0.164 /usr/lib64/python2.6/ConfigParser.py:515(ConfigParser.get) 1/0 0.380 0.380 /usr/lib64/python2.6/StringIO.py:208(StringIO.write) 6/4 11.095 11.095 /usr/lib64/python2.6/copy.py:144(deepcopy) 1 4.246 4.251 /usr/lib64/python2.6/copy.py:251(_deepcopy_dict) 3/2 6.132 6.132 /usr/lib64/python2.6/copy.py:261(_keep_alive) 3/1 2.525 2.525 /usr/lib64/python2.6/genericpath.py:85(_splitext) 3/0 1.717 1.717 /usr/lib64/python2.6/logging/__init__.py:148(getLevelName) 31/3 6.857 6.859 /usr/lib64/python2.6/logging/__init__.py:226(LogRecord.__init__) 1 0.741 0.741 /usr/lib64/python2.6/logging/__init__.py:566(Logger.filter) 9/1 4.974 4.974 /usr/lib64/python2.6/logging/__init__.py:1121(Logger.findCaller) 8/1 10.515 10.521 /usr/lib64/python2.6/logging/__init__.py:1155(Logger._log) 1 0.010 0.010 /usr/lib64/python2.6/logging/__init__.py:1175(Logger.handle) 1/0 0.027 0.028 /usr/lib64/python2.6/logging/__init__.py:1204(Logger.callHandlers) 6/2 4.241 4.241 /usr/lib64/python2.6/posixpath.py:94(splitext) 2/1 0.015 0.015 /usr/lib64/python2.6/posixpath.py:109(basename) 1 2.064 2.064 /usr/lib64/python2.6/socket.py:189(_socketobject.close) 1 0.001 0.001 /usr/lib64/python2.6/threading.py:376(_Event.set) 1/0 1.105 1.105 /usr/lib64/python2.6/threading.py:391(_Event.wait) 1/0 0.000 0.095 /usr/lib64/python2.6/threading.py:481(Thread.run) 1/0 0.053 0.053 /usr/lib64/python2.6/threading.py:808(currentThread) 1/0 0.446 0.446 /usr/lib64/python2.6/xml/dom/expatbuilder.py:274(ExpatBuilderNS.character_data_handler_cdata) 1 0.471 0.471 /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) 1 0.347 0.347 /usr/lib64/python2.6/xml/dom/minidom.py:305(_get_elements_by_tagName_helper) 1/0 0.000 0.000 /usr/share/vdsm/protocoldetector.py:77(MultiProtocolAcceptor.serve_forever) 1/0 0.000 0.004 /usr/share/vdsm/rpc/Bridge.py:249(DynamicBridge._dynamicMethod) 1/0 0.336 0.345 /usr/share/vdsm/supervdsm.py:45(ProxyCaller.__call__) 1 0.015 0.015 /usr/share/vdsm/virt/migration.py:21() 1 0.115 0.115 /usr/share/vdsm/virt/sampling.py:337(AdvancedStatsFunction.interval) /usr/lib64/python2.6/copy.py:261(_keep_alive) <- 3 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 10429409 28.526 40.547 /usr/lib64/python2.6/copy.py:144(deepcopy) 8 0.000 0.000 /usr/lib64/python2.6/copy.py:224(_deepcopy_list) 26 0.000 0.000 /usr/lib64/python2.6/copy.py:251(_deepcopy_dict) 4 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 1 0.000 0.000 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) /usr/lib64/python2.6/copy.py:251(_deepcopy_dict) <- 1122019/96639 23.297 168.826 /usr/lib64/python2.6/copy.py:144(deepcopy) /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) <- 65621 22.852 75.526 /usr/share/vdsm/virt/vm.py:2867(Vm.getStats) /usr/share/vdsm/protocoldetector.py:94(MultiProtocolAcceptor._process_events) <- 2112908/2112907 15.235 22.122 /usr/lib64/python2.6/threading.py:481(Thread.run) 454013 2.969 4.424 /usr/share/vdsm/protocoldetector.py:77(MultiProtocolAcceptor.serve_forever) /usr/share/vdsm/virt/sampling.py:452(VmStatsThread.collect) <- 1 0.098 0.771 /usr/lib64/python2.6/threading.py:376(_Event.set) 83 7.516 61.672 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) /usr/lib/python2.6/site-packages/vdsm/utils.py:424(convertToStr) <- 21 0.000 0.000 /usr/lib64/python2.6/ConfigParser.py:339(ConfigParser.getint) 913 0.002 0.002 /usr/share/vdsm/API.py:1277(Global.getStats) 38 0.000 0.000 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) 1353483 8.071 8.071 /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) 65580 0.541 0.541 /usr/share/vdsm/virt/vm.py:3010(Vm._getGuestStats) /usr/lib64/python2.6/inspect.py:247(getmembers) <- 27178 6.568 15.722 /usr/share/vdsm/rpc/Bridge.py:159(DynamicBridge._getDefaultArgs) /usr/lib64/python2.6/copy.py:197(_deepcopy_atomic) <- 2 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 9245234 5.549 5.549 /usr/lib64/python2.6/copy.py:144(deepcopy) 2 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 1 0.000 0.000 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) <- 152719 5.384 10.612 /usr/lib64/python2.6/xml/dom/expatbuilder.py:62(ElementInfo) 2707 0.162 0.276 /usr/lib64/python2.6/xml/dom/expatbuilder.py:350(ExpatBuilderNS.first_element_handler) /usr/share/vdsm/virt/vm.py:532(VmStatsThread._getDiskStats) <- 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:398(VmStatsThread._getCpuStats) 65580 4.971 9.881 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) /usr/share/vdsm/virt/sampling.py:355(AdvancedStatsFunction.getStats) <- 65581 0.908 0.938 /usr/share/vdsm/virt/vm.py:398(VmStatsThread._getCpuStats) 65581 0.836 0.861 /usr/share/vdsm/virt/vm.py:421(VmStatsThread._getBalloonStats) 65581 0.174 0.193 /usr/share/vdsm/virt/vm.py:441(VmStatsThread._getCpuTuneInfo) 65581 0.798 0.821 /usr/share/vdsm/virt/vm.py:461(VmStatsThread._getCpuCount) 65581 0.781 0.804 /usr/share/vdsm/virt/vm.py:475(VmStatsThread._getUserCpuTuneInfo) 65581 0.178 0.201 /usr/share/vdsm/virt/vm.py:513(VmStatsThread._getNetworkStats) 65581 0.148 0.165 /usr/share/vdsm/virt/vm.py:532(VmStatsThread._getDiskStats) 65581 0.858 0.886 /usr/share/vdsm/virt/vm.py:557(VmStatsThread._getDiskLatency) 65581 0.167 0.188 /usr/share/vdsm/virt/vm.py:601(VmStatsThread._getVmJobs) /usr/share/vdsm/virt/vm.py:2991(Vm._getGraphicsStats) <- 1 0.000 0.000 /usr/lib64/python2.6/ConfigParser.py:339(ConfigParser.getint) 2 0.000 0.000 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) 65618 4.420 5.992 /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) /usr/lib/python2.6/site-packages/mom/Policy/spark.py:211(Parser.buildState) <- 17897 3.994 4.767 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:187(Parser.parse) /usr/lib64/python2.6/inspect.py:59(isclass) <- 679450 3.976 5.225 /usr/lib64/python2.6/inspect.py:247(getmembers) /usr/lib64/python2.6/asyncore.py:165(poll2) <- 42358/42357 3.938 26.644 /usr/lib64/python2.6/asyncore.py:199(loop) /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) 283 0.000 0.002 /usr/lib/python2.6/site-packages/vdsm/utils.py:387(iteratePids) 125411 3.617 17.433 /usr/lib64/python2.6/glob.py:10(glob) 99/0 0.001 0.009 /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/misc.py:758(helper) 1 0.000 0.001 /usr/share/vdsm/storage/storageServer.py:576(ConnectionAliasRegistrar._iterPersistedConnectionInfo) /usr/lib/python2.6/site-packages/pthread.py:133(Cond.timedwait) <- 52229/52204 3.551 3.906 /usr/lib/python2.6/site-packages/pthreading.py:115(Condition.wait) /usr/share/vdsm/virt/vm.py:557(VmStatsThread._getDiskLatency) <- 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:398(VmStatsThread._getCpuStats) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:532(VmStatsThread._getDiskStats) 65579 3.449 7.770 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) /usr/share/vdsm/virt/sampling.py:337(AdvancedStatsFunction.interval) <- 11250 0.029 0.029 /usr/lib64/python2.6/threading.py:391(_Event.wait) 10776/0 0.026 0.026 /usr/share/vdsm/virt/sampling.py:337(AdvancedStatsFunction.interval) 9913 0.025 0.025 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) 1269821 3.334 3.986 /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/sampling.py:433(VmStatsThread.run) <- 1 0.246 0.246 /usr/lib/python2.6/site-packages/vdsm/utils.py:817(tobool) 1 0.145 0.145 /usr/lib64/python2.6/copy.py:144(deepcopy) 1 0.002 0.002 /usr/lib64/python2.6/inspect.py:67(ismethod) 1 0.006 0.006 /usr/lib64/python2.6/inspect.py:247(getmembers) 1 0.556 0.556 /usr/lib64/python2.6/logging/__init__.py:566(Logger.filter) 7 1.663 1.663 /usr/lib64/python2.6/socket.py:182(_socketobject.__init__) 1 0.002 0.002 /usr/lib64/python2.6/threading.py:808(currentThread) 1 0.310 0.315 /usr/share/vdsm/virt/guestagent.py:115(GuestAgent.__init__) 1/0 0.242 0.761 /usr/share/vdsm/virt/sampling.py:452(VmStatsThread.collect) 1 0.048 0.048 /usr/share/vdsm/virt/vm.py:398(VmStatsThread._getCpuStats) /usr/share/vdsm/virt/vm.py:560(_avgLatencyCalc) <- 121138 3.154 3.154 /usr/share/vdsm/virt/vm.py:557(VmStatsThread._getDiskLatency) /usr/share/vdsm/virt/vm.py:2537(Vm._getExtendCandidates) <- 40701 2.970 19.818 /usr/share/vdsm/virt/vm.py:2591(Vm.extendDrivesIfNeeded) /usr/share/vdsm/virt/vm.py:421(VmStatsThread._getBalloonStats) <- 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:398(VmStatsThread._getCpuStats) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:532(VmStatsThread._getDiskStats) 65579 2.931 3.905 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) /usr/share/vdsm/virt/vm.py:2860() <- 1090348 2.886 3.348 /usr/share/vdsm/virt/vm.py:2855(Vm.status) /usr/lib/python2.6/site-packages/mom/Monitor.py:53(HostMonitor.collect) <- 9293 1.625 67.770 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 200 0.039 3.760 /usr/lib/python2.6/site-packages/mom/HostMonitor.py:47(HostMonitor.run) 457/0 0.079 3.474 /usr/lib/python2.6/site-packages/mom/Monitor.py:53(HostMonitor.collect) /usr/lib64/python2.6/fnmatch.py:45(filter) <- 125372 2.611 2.753 /usr/lib64/python2.6/glob.py:49(glob1) /usr/lib64/python2.6/glob.py:49(glob1) <- 125379 2.556 8.980 /usr/lib64/python2.6/glob.py:18(iglob) /usr/lib64/python2.6/logging/__init__.py:1204(Logger.callHandlers) <- 74576 2.423 29.220 /usr/lib64/python2.6/logging/__init__.py:1175(Logger.handle) 1 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) /usr/share/vdsm/virt/vm.py:1943(Vm._get_lastStatus) <- 2 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/vdsm/utils.py:817(tobool) 3215 0.008 0.008 /usr/share/vdsm/API.py:1647(Global._countVms) 9 0.000 0.000 /usr/share/vdsm/virt/guestagent.py:115(GuestAgent.__init__) 86 0.001 0.001 /usr/share/vdsm/virt/vm.py:2263(Vm._startUnderlyingVm) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:2346(Vm.preparePaths) 397 0.002 0.002 /usr/share/vdsm/virt/vm.py:2399(Vm._saveStateInternal) 31167 0.153 0.153 /usr/share/vdsm/virt/vm.py:2855(Vm.status) 65621 0.770 0.770 /usr/share/vdsm/virt/vm.py:2867(Vm.getStats) 131339 1.439 1.439 /usr/share/vdsm/virt/vm.py:2962(Vm._getVmStatus) 2 0.000 0.000 /usr/share/vdsm/virt/vm.py:3192(Vm._getUnderlyingVmDevicesInfo) 89 0.001 0.001 /usr/share/vdsm/virt/vm.py:3252(Vm._domDependentInit) 11 0.000 0.000 /usr/share/vdsm/virt/vm.py:3309(Vm._run) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:4821(Vm._getUnderlyingVmInfo) /usr/lib64/python2.6/xml/dom/minidom.py:349(Attr.__init__) <- 1 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 282016 2.267 2.566 /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) 12200 0.099 0.103 /usr/lib64/python2.6/xml/dom/minidom.py:692(Element.setAttribute) /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) 4/0 0.000 0.000 /usr/lib64/python2.6/StringIO.py:208(StringIO.write) 87200 0.723 0.876 /usr/lib64/python2.6/codecs.py:347(StreamWriter.write) 85203/85201 0.687 0.837 /usr/lib64/python2.6/xml/dom/minidom.py:299(_write_data) 165192 0.935 1.602 /usr/share/vdsm/virt/vm.py:6012(Element.__hacked_writexml) /usr/lib64/python2.6/logging/__init__.py:423(Formatter.format) <- 130142 2.333 6.620 /usr/lib64/python2.6/logging/__init__.py:643(WatchedFileHandler.format) /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) <- 65581 2.323 33.361 /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) /usr/lib64/python2.6/logging/__init__.py:764(WatchedFileHandler.emit) <- 1 0.000 0.000 /usr/lib64/python2.6/logging/__init__.py:566(Logger.filter) 54953 0.926 8.714 /usr/lib64/python2.6/logging/__init__.py:666(WatchedFileHandler.handle) 74577 1.334 8.124 /usr/lib64/python2.6/logging/__init__.py:851(WatchedFileHandler.emit) /usr/share/vdsm/API.py:356(VM.getStats) <- 16564 0.722 36.439 /usr/lib/python2.6/site-packages/mom/HypervisorInterfaces/vdsmInterface.py:87(vdsmInterface.getVmMemoryStats) 16564 0.621 32.728 /usr/lib/python2.6/site-packages/mom/HypervisorInterfaces/vdsmInterface.py:142(vdsmInterface.getVmBalloonInfo) 16564 0.590 31.602 /usr/lib/python2.6/site-packages/mom/HypervisorInterfaces/vdsmInterface.py:166(vdsmInterface.getVmCpuTuneInfo) 3188 0.033 2.034 /usr/share/vdsm/API.py:1263(Global.getAllVmStats) 27 0.000 0.007 /usr/share/vdsm/API.py:1346(Global.getVMList) 3 0.000 0.009 /usr/share/vdsm/rpc/Bridge.py:124(DynamicBridge._getMethodArgs) 12858 0.239 12.014 /usr/share/vdsm/rpc/Bridge.py:249(DynamicBridge._dynamicMethod) /usr/lib/python2.6/site-packages/mom/Policy/spark.py:103(Parser.addRule) <- 22680 2.125 2.428 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:127(Parser.collectRules) /usr/lib64/python2.6/xml/dom/minidom.py:305(_get_elements_by_tagName_helper) <- 12/0 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 580053/0 1.940 2.287 /usr/lib64/python2.6/xml/dom/minidom.py:305(_get_elements_by_tagName_helper) 9958 0.151 2.658 /usr/lib64/python2.6/xml/dom/minidom.py:790(Element.getElementsByTagName) 809 0.005 0.054 /usr/lib64/python2.6/xml/dom/minidom.py:1725(Document.getElementsByTagName) /usr/lib/python2.6/site-packages/pthreading.py:66(Lock.acquire) <- 160071 0.824 2.222 /usr/lib/python2.6/site-packages/pthreading.py:59(Lock.__enter__) 1 0.000 0.000 /usr/lib/python2.6/site-packages/vdsm/utils.py:594(AsyncProc._processStreams) 933 0.002 0.005 /usr/lib64/python2.6/Queue.py:45(Queue.task_done) 933 0.002 0.005 /usr/lib64/python2.6/Queue.py:93(Queue.empty) 15878 0.041 0.104 /usr/lib64/python2.6/Queue.py:107(Queue.put) 23863 0.130 0.349 /usr/lib64/python2.6/Queue.py:150(Queue.get) 3005 0.011 0.031 /usr/lib64/python2.6/logging/__init__.py:194(_acquireLock) 129633 0.512 1.366 /usr/lib64/python2.6/logging/__init__.py:623(WatchedFileHandler.acquire) 1 0.000 0.000 /usr/lib64/python2.6/multiprocessing/managers.py:666(AutoProxy[instance].__init__) 400 0.001 0.003 /usr/lib64/python2.6/tempfile.py:127(_RandomNameSequence.next) 1788 0.000 0.024 /usr/lib64/python2.6/threading.py:310(_Semaphore.acquire) 1788 0.006 0.019 /usr/lib64/python2.6/threading.py:331(_Semaphore.release) 2665 0.009 0.026 /usr/lib64/python2.6/threading.py:376(_Event.set) 2 0.000 0.000 /usr/lib64/python2.6/threading.py:384(_Event.clear) 103315 0.678 1.791 /usr/lib64/python2.6/threading.py:391(_Event.wait) 13938 0.039 0.100 /usr/lib64/python2.6/threading.py:463(Thread.start) 1 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 13899 0.079 0.225 /usr/lib64/python2.6/threading.py:588(Thread.__stop) 100 0.000 0.001 /usr/share/vdsm/API.py:328(VM.destroy) 6 0.000 0.000 /usr/share/vdsm/storage/misc.py:658(DynamicBarrier.enter) 537 0.002 0.004 /usr/share/vdsm/storage/misc.py:868(OperationMutex.acquire) 303 0.001 0.002 /usr/share/vdsm/storage/resourceManager.py:789(Owner.acquire) 2058 0.008 0.020 /usr/share/vdsm/storage/resourceManager.py:938(Owner.releaseAll) 303 0.001 0.002 /usr/share/vdsm/storage/resourceManager.py:950(Owner.release) 2059 0.007 0.019 /usr/share/vdsm/storage/resourceManager.py:976(Owner.cancelAll) 303 0.001 0.002 /usr/share/vdsm/storage/task.py:820(Task.resourceAcquired) 2 0.000 0.000 /usr/share/vdsm/storage/task.py:927(Task._doAbort) 2363 0.010 0.025 /usr/share/vdsm/storage/task.py:975(Task._incref) 2363 0.008 0.021 /usr/share/vdsm/storage/task.py:987(Task._decref) 2 0.000 0.000 /usr/share/vdsm/storage/threadPool.py:46(ThreadPool.setRunningTask) 3 0.000 0.000 /usr/share/vdsm/storage/threadPool.py:64(ThreadPool.setThreadCount) /usr/lib/python2.6/site-packages/pthreading.py:115(Condition.wait) <- 21082/21059 0.302 1.192 /usr/lib64/python2.6/Queue.py:150(Queue.get) 58665/58661 1.764 5.510 /usr/lib64/python2.6/threading.py:391(_Event.wait) 3 0.000 0.000 /usr/share/vdsm/storage/sdc.py:101(StorageDomainCache._realProduce) /usr/share/vdsm/virt/vm.py:2962(Vm._getVmStatus) <- 65618 2.052 6.576 /usr/share/vdsm/virt/vm.py:2867(Vm.getStats) 3 0.000 0.001 /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) /usr/lib64/python2.6/inspect.py:67(ismethod) <- 6 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 49/0 0.000 0.000 /usr/lib64/python2.6/inspect.py:67(ismethod) 796644 1.992 2.304 /usr/lib64/python2.6/inspect.py:247(getmembers) 13539 0.033 0.037 /usr/lib64/python2.6/inspect.py:791(getargspec) 2059 0.011 0.014 /usr/share/vdsm/logUtils.py:27(funcName) 2058 0.011 0.014 /usr/share/vdsm/logUtils.py:55(call2str) 18 0.000 0.000 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) /usr/lib64/python2.6/glob.py:77(has_magic) <- 376165 1.946 2.630 /usr/lib64/python2.6/glob.py:18(iglob) /usr/lib64/python2.6/posixpath.py:79(split) <- 125388 1.887 2.208 /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/lib64/python2.6/xml/dom/minidom.py:281(Document._append_child) <- 1 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 8100 0.040 0.043 /usr/lib64/python2.6/xml/dom/__init__.py:90(NoDataAllowedErr) 216573 0.952 1.030 /usr/lib64/python2.6/xml/dom/expatbuilder.py:274(ExpatBuilderNS.character_data_handler_cdata) 80 0.000 0.000 /usr/lib64/python2.6/xml/dom/expatbuilder.py:333(ExpatBuilderNS.comment_handler) 155426 0.859 0.903 /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) /usr/share/vdsm/virt/migration.py:21() <- 1 0.000 0.001 /usr/share/vdsm/clientIF.py:21() 3/0 0.000 0.000 /usr/share/vdsm/virt/migration.py:21() 65781 0.052 0.052 /usr/share/vdsm/virt/sampling.py:49(InterfaceSample) /usr/lib64/python2.6/logging/__init__.py:666(WatchedFileHandler.handle) <- 1/0 0.000 0.000 /usr/lib64/python2.6/logging/__init__.py:666(WatchedFileHandler.handle) 129631 1.791 26.769 /usr/lib64/python2.6/logging/__init__.py:1204(Logger.callHandlers) 1 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) /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() 125302 1.764 20.562 /usr/share/vdsm/hooks.py:53(_runHooksDir) 51 0.000 0.002 /usr/share/vdsm/hooks.py:381(_getHookInfo)