Mon Nov 10 13:50:29 2014 baseline_jsonrpc.prof 60351712 function calls (96981783 primitive calls) in 823.499 CPU seconds Ordered by: internal time List reduced from 2613 to 50 due to restriction <50> ncalls tottime percall cumtime percall filename:lineno(function) 98/100 197.061 2.011 265.583 2.656 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 13331/13412 117.132 0.009 294.482 0.022 /usr/lib64/python2.6/threading.py:481(Thread.run) 97439/12454808 71.739 0.001 193.515 0.000 /usr/lib64/python2.6/copy.py:144(deepcopy) 100 38.230 0.382 103.942 1.039 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) 96751/1103236 30.798 0.000 191.919 0.000 /usr/lib64/python2.6/copy.py:251(_deepcopy_dict) 66286 24.808 0.000 82.338 0.001 /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) 4107389/16859232 23.189 0.000 48.677 0.000 /usr/lib64/python2.6/json/encoder.py:284(JSONEncoder._iterencode) 4107412/10861908 17.214 0.000 44.677 0.000 /usr/lib64/python2.6/json/encoder.py:213(JSONEncoder._iterencode_dict) 100 9.888 0.099 77.989 0.780 /usr/share/vdsm/virt/sampling.py:452(VmStatsThread.collect) 1435169/1435182 9.272 0.000 9.313 0.000 /usr/lib/python2.6/site-packages/vdsm/utils.py:424(convertToStr) 155426 7.075 0.000 12.857 0.000 /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) 9261300 6.382 0.000 6.382 0.000 /usr/lib64/python2.6/copy.py:197(_deepcopy_atomic) 26580 6.325 0.000 15.208 0.001 /usr/lib64/python2.6/inspect.py:247(getmembers) 66247 5.381 0.000 10.656 0.000 /usr/share/vdsm/virt/vm.py:532(VmStatsThread._getDiskStats) 3877316/4264880 5.377 0.000 33.614 0.000 /usr/lib64/python2.6/json/encoder.py:180(JSONEncoder._iterencode_list) 596223 5.208 0.000 5.428 0.000 /usr/share/vdsm/virt/sampling.py:355(AdvancedStatsFunction.getStats) 66286 4.959 0.000 6.699 0.000 /usr/share/vdsm/virt/vm.py:2991(Vm._getGraphicsStats) 41393/41394 4.144 0.000 26.566 0.001 /usr/lib64/python2.6/asyncore.py:165(poll2) 127280/127381 3.957 0.000 18.753 0.000 /usr/lib64/python2.6/glob.py:18(iglob) 664500 3.818 0.000 5.060 0.000 /usr/lib64/python2.6/inspect.py:59(isclass) 66247 3.731 0.000 8.416 0.000 /usr/share/vdsm/virt/vm.py:557(VmStatsThread._getDiskLatency) 1330090 3.712 0.000 3.712 0.000 /usr/share/vdsm/virt/sampling.py:337(AdvancedStatsFunction.interval) 53264/53289 3.586 0.000 3.964 0.000 /usr/lib/python2.6/site-packages/pthread.py:133(Cond.timedwait) 122704 3.443 0.000 3.443 0.000 /usr/share/vdsm/virt/vm.py:560(_avgLatencyCalc) 13821/29402 3.388 0.000 7.744 0.000 /usr/lib64/python2.6/json/decoder.py:162(JSONObject) 66247 3.224 0.000 4.377 0.000 /usr/share/vdsm/virt/vm.py:421(VmStatsThread._getBalloonStats) 41536 3.199 0.000 20.692 0.000 /usr/share/vdsm/virt/vm.py:2537(Vm._getExtendCandidates) 16522/17109 3.112 0.000 131.436 0.008 /usr/lib/python2.6/site-packages/mom/Monitor.py:53(HostMonitor.collect) 294217 3.097 0.000 3.213 0.000 /usr/lib64/python2.6/xml/dom/minidom.py:349(Attr.__init__) 18049/18050 2.832 0.000 5.627 0.000 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:211(Parser.buildState) 126844 2.813 0.000 2.965 0.000 /usr/lib64/python2.6/fnmatch.py:45(filter) 10767/590832 2.806 0.000 3.450 0.000 /usr/lib64/python2.6/xml/dom/minidom.py:305(_get_elements_by_tagName_helper) 337600 2.789 0.000 3.862 0.000 /usr/lib64/python2.6/StringIO.py:208(StringIO.write) 126851 2.732 0.000 9.543 0.000 /usr/lib64/python2.6/glob.py:49(glob1) 233287 2.646 0.000 2.646 0.000 /usr/share/vdsm/virt/vm.py:1943(Vm._get_lastStatus) 22905 2.622 0.000 2.966 0.000 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:103(Parser.addRule) 74074 2.569 0.000 30.276 0.000 /usr/lib64/python2.6/logging/__init__.py:1204(Logger.callHandlers) 66247 2.521 0.000 36.193 0.001 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) 481556 2.489 0.000 6.662 0.000 /usr/lib/python2.6/site-packages/pthreading.py:66(Lock.acquire) 128915 2.454 0.000 6.934 0.000 /usr/lib64/python2.6/logging/__init__.py:423(Formatter.format) 216574 2.415 0.000 3.658 0.000 /usr/lib64/python2.6/xml/dom/expatbuilder.py:274(ExpatBuilderNS.character_data_handler_cdata) 66424 2.367 0.000 124.848 0.002 /usr/share/vdsm/API.py:356(VM.getStats) 128314 2.327 0.000 17.415 0.000 /usr/lib64/python2.6/logging/__init__.py:764(WatchedFileHandler.emit) 66286 2.245 0.000 7.195 0.000 /usr/share/vdsm/virt/vm.py:2962(Vm._getVmStatus) 380180 2.238 0.000 2.373 0.000 /usr/lib64/python2.6/xml/dom/minidom.py:281(Document._append_child) 80192/80219 2.177 0.000 6.882 0.000 /usr/lib/python2.6/site-packages/pthreading.py:115(Condition.wait) 380581 2.114 0.000 2.832 0.000 /usr/lib64/python2.6/glob.py:77(has_magic) 126969 2.082 0.000 2.418 0.000 /usr/lib64/python2.6/posixpath.py:79(split) 4581 2.081 0.000 2.297 0.001 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:146(Parser.makeFIRST) 87177 2.057 0.000 4.033 0.000 /usr/lib64/python2.6/ConfigParser.py:515(ConfigParser.get) Ordered by: internal time List reduced from 2613 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) <- 9 45.563 45.563 /usr/lib/python2.6/site-packages/pthread.py:95(Lock.lock) 4 9.591 9.591 /usr/lib/python2.6/site-packages/pthread.py:98(Lock.unlock) 1 0.956 0.956 /usr/lib/python2.6/site-packages/pthread.py:123(Cond.signal) 2 13.334 13.334 /usr/lib/python2.6/site-packages/pthreading.py:77(Lock.release) 1 3.202 3.202 /usr/lib/python2.6/site-packages/pthreading.py:129(Condition.notify) 1 0.443 0.443 /usr/lib64/python2.6/StringIO.py:208(StringIO.write) 5 3.591 3.591 /usr/lib64/python2.6/copy.py:144(deepcopy) 1 0.931 0.931 /usr/lib64/python2.6/copy.py:251(_deepcopy_dict) 1 0.181 0.181 /usr/lib64/python2.6/copy.py:261(_keep_alive) 1 0.823 0.825 /usr/lib64/python2.6/json/scanner.py:38(Scanner.iterscan) 2 3.568 3.568 /usr/lib64/python2.6/threading.py:64(Thread._note) 5 24.021 24.021 /usr/lib64/python2.6/threading.py:310(_Semaphore.acquire) 6 57.690 57.690 /usr/lib64/python2.6/threading.py:331(_Semaphore.release) 1 0.605 0.605 /usr/lib64/python2.6/threading.py:371(_Event.isSet) 1 6.297 6.297 /usr/lib64/python2.6/threading.py:391(_Event.wait) 2 26.005 26.005 /usr/lib64/python2.6/threading.py:677(MomThread.isAlive) 1 0.283 0.283 /usr/lib64/python2.6/xml/dom/expatbuilder.py:801(ExpatBuilderNS.end_element_handler) 1 0.069 0.069 /usr/share/vdsm/virt/sampling.py:49(InterfaceSample) 1/0 0.274 0.274 /usr/share/vdsm/virt/vm.py:3010(Vm._getGuestStats) 1 0.438 0.438 /usr/share/vdsm/virt/vm.py:6012(Element.__hacked_writexml) /usr/lib64/python2.6/threading.py:481(Thread.run) <- 1 0.040 0.040 /usr/lib/python2.6/site-packages/vdsm/utils.py:424(convertToStr) 4/3 5.396 5.396 /usr/lib64/python2.6/copy.py:144(deepcopy) 3 54.951 54.951 /usr/lib64/python2.6/copy.py:261(_keep_alive) 6/2 0.043 0.043 /usr/lib64/python2.6/genericpath.py:85(_splitext) 3/0 1.060 1.060 /usr/lib64/python2.6/logging/__init__.py:76() 6/1 0.079 0.079 /usr/lib64/python2.6/logging/__init__.py:148(getLevelName) 30/2 14.692 14.694 /usr/lib64/python2.6/logging/__init__.py:226(LogRecord.__init__) 1 0.001 0.001 /usr/lib64/python2.6/logging/__init__.py:423(Formatter.format) 1/0 0.017 0.017 /usr/lib64/python2.6/logging/__init__.py:566(Logger.filter) 1 0.011 0.011 /usr/lib64/python2.6/logging/__init__.py:623(WatchedFileHandler.acquire) 1/0 0.032 0.032 /usr/lib64/python2.6/logging/__init__.py:764(WatchedFileHandler.emit) 1/0 0.006 0.007 /usr/lib64/python2.6/logging/__init__.py:1034(Logger.debug) 7/0 5.081 5.081 /usr/lib64/python2.6/logging/__init__.py:1121(Logger.findCaller) 9/1 0.086 0.086 /usr/lib64/python2.6/posixpath.py:94(splitext) 2/1 0.018 0.018 /usr/lib64/python2.6/posixpath.py:109(basename) 2 14.244 14.244 /usr/lib64/python2.6/threading.py:371(_Event.isSet) 1/0 0.192 0.192 /usr/lib64/python2.6/threading.py:391(_Event.wait) 2/0 0.000 0.105 /usr/lib64/python2.6/threading.py:481(Thread.run) 1/0 0.600 0.600 /usr/lib64/python2.6/xml/dom/expatbuilder.py:801(ExpatBuilderNS.end_element_handler) 1 0.640 0.640 /usr/lib64/python2.6/xml/dom/minidom.py:305(_get_elements_by_tagName_helper) 1 0.004 0.005 /usr/share/vdsm/logUtils.py:40(wrapper) 1/0 0.000 0.000 /usr/share/vdsm/protocoldetector.py:77(MultiProtocolAcceptor.serve_forever) 5/0 22.167 22.167 /usr/share/vdsm/protocoldetector.py:94(MultiProtocolAcceptor._process_events) 1/0 0.000 0.006 /usr/share/vdsm/rpc/Bridge.py:249(DynamicBridge._dynamicMethod) 1 0.125 0.798 /usr/share/vdsm/virt/sampling.py:452(VmStatsThread.collect) 1 0.204 0.204 /usr/share/vdsm/virt/vm.py:398(VmStatsThread._getCpuStats) 1/0 0.132 0.135 /usr/share/vdsm/virt/vm.py:6012(Element.__hacked_writexml) /usr/lib64/python2.6/copy.py:144(deepcopy) <- 8/0 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 523/0 0.015 0.156 /usr/lib64/python2.6/copy.py:144(deepcopy) 396089/0 3.315 149.826 /usr/lib64/python2.6/copy.py:224(_deepcopy_list) 11960749/0 67.543 186.798 /usr/lib64/python2.6/copy.py:251(_deepcopy_dict) 100 0.003 0.032 /usr/share/vdsm/API.py:328(VM.destroy) 66247 0.527 1.380 /usr/share/vdsm/virt/vm.py:601(VmStatsThread._getVmJobs) 100 0.003 0.720 /usr/share/vdsm/virt/vm.py:2026(Vm.buildConfDevices) 399 0.004 0.011 /usr/share/vdsm/virt/vm.py:2399(Vm._saveStateInternal) 30592 0.329 191.354 /usr/share/vdsm/virt/vm.py:2855(Vm.status) 1 0.000 0.019 /usr/share/vdsm/virt/vm.py:2860() /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) <- 1 1.941 1.941 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:211(Parser.buildState) 1 0.013 0.013 /usr/lib/python2.6/site-packages/yajsonrpc/stompReactor.py:266(_AsyncoreEvent.set) 2 15.030 15.030 /usr/lib64/python2.6/copy.py:144(deepcopy) 1 1.102 1.102 /usr/lib64/python2.6/copy.py:261(_keep_alive) 1 0.026 0.026 /usr/lib64/python2.6/json/encoder.py:284(JSONEncoder._iterencode) 5 1.465 1.465 /usr/lib64/python2.6/socket.py:182(_socketobject.__init__) 1 0.440 0.440 /usr/share/vdsm/supervdsm.py:86(SuperVdsmProxy.__getattr__) 2 0.484 0.493 /usr/share/vdsm/virt/guestagent.py:115(GuestAgent.__init__) 1 17.519 17.519 /usr/share/vdsm/virt/vm.py:2860() 1 0.205 0.205 /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) /usr/lib64/python2.6/copy.py:251(_deepcopy_dict) <- 1103236/96751 30.798 191.919 /usr/lib64/python2.6/copy.py:144(deepcopy) /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) <- 66286 24.808 82.338 /usr/share/vdsm/virt/vm.py:2867(Vm.getStats) /usr/lib64/python2.6/json/encoder.py:284(JSONEncoder._iterencode) <- 4175542/0 4.954 28.795 /usr/lib64/python2.6/json/encoder.py:180(JSONEncoder._iterencode_list) 8576278/0 14.286 39.335 /usr/lib64/python2.6/json/encoder.py:213(JSONEncoder._iterencode_dict) 23/0 0.000 0.001 /usr/lib64/python2.6/json/encoder.py:284(JSONEncoder._iterencode) 4107389 3.949 48.677 /usr/lib64/python2.6/json/encoder.py:352(JSONEncoder.encode) /usr/lib64/python2.6/json/encoder.py:213(JSONEncoder._iterencode_dict) <- 10861908/4107412 17.214 44.677 /usr/lib64/python2.6/json/encoder.py:284(JSONEncoder._iterencode) /usr/share/vdsm/virt/sampling.py:452(VmStatsThread.collect) <- 84 8.398 65.562 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) /usr/lib/python2.6/site-packages/vdsm/utils.py:424(convertToStr) <- 13/0 0.000 0.000 /usr/lib/python2.6/site-packages/vdsm/utils.py:424(convertToStr) 892 0.004 0.004 /usr/share/vdsm/API.py:1277(Global.getStats) 20 0.000 0.000 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) 38 0.001 0.001 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) 1367972 8.689 8.730 /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) 66247 0.578 0.578 /usr/share/vdsm/virt/vm.py:3010(Vm._getGuestStats) /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) <- 152719 6.935 12.615 /usr/lib64/python2.6/xml/dom/expatbuilder.py:62(ElementInfo) 2707 0.140 0.242 /usr/lib64/python2.6/xml/dom/expatbuilder.py:350(ExpatBuilderNS.first_element_handler) /usr/lib64/python2.6/copy.py:197(_deepcopy_atomic) <- 4 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 9261290 6.382 6.382 /usr/lib64/python2.6/copy.py:144(deepcopy) 4 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 2 0.000 0.000 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) /usr/lib64/python2.6/inspect.py:247(getmembers) <- 26580 6.325 15.208 /usr/share/vdsm/rpc/Bridge.py:159(DynamicBridge._getDefaultArgs) /usr/share/vdsm/virt/vm.py:532(VmStatsThread._getDiskStats) <- 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:398(VmStatsThread._getCpuStats) 66246 5.380 10.655 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) /usr/lib64/python2.6/json/encoder.py:180(JSONEncoder._iterencode_list) <- 4264879/3877315 5.377 33.614 /usr/lib64/python2.6/json/encoder.py:284(JSONEncoder._iterencode) 1 0.000 0.000 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) /usr/share/vdsm/virt/sampling.py:355(AdvancedStatsFunction.getStats) <- 1 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 66246 0.990 1.022 /usr/share/vdsm/virt/vm.py:398(VmStatsThread._getCpuStats) 66247 0.904 0.931 /usr/share/vdsm/virt/vm.py:421(VmStatsThread._getBalloonStats) 66247 0.186 0.206 /usr/share/vdsm/virt/vm.py:441(VmStatsThread._getCpuTuneInfo) 66247 0.863 0.887 /usr/share/vdsm/virt/vm.py:461(VmStatsThread._getCpuCount) 66247 0.844 0.868 /usr/share/vdsm/virt/vm.py:475(VmStatsThread._getUserCpuTuneInfo) 66247 0.179 0.202 /usr/share/vdsm/virt/vm.py:513(VmStatsThread._getNetworkStats) 66247 0.148 0.165 /usr/share/vdsm/virt/vm.py:532(VmStatsThread._getDiskStats) 66247 0.924 0.953 /usr/share/vdsm/virt/vm.py:557(VmStatsThread._getDiskLatency) 66247 0.171 0.193 /usr/share/vdsm/virt/vm.py:601(VmStatsThread._getVmJobs) /usr/share/vdsm/virt/vm.py:2991(Vm._getGraphicsStats) <- 1 0.000 0.000 /usr/lib/python2.6/site-packages/vdsm/utils.py:424(convertToStr) 1 0.000 0.000 /usr/share/vdsm/virt/sampling.py:433(VmStatsThread.run) 2 0.000 0.000 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) 66282 4.958 6.698 /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) /usr/lib64/python2.6/asyncore.py:165(poll2) <- 41394/41393 4.144 26.566 /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) 126884 3.955 18.743 /usr/lib64/python2.6/glob.py:10(glob) 101/0 0.001 0.006 /usr/lib64/python2.6/glob.py:18(iglob) 15 0.000 0.002 /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/lib64/python2.6/inspect.py:59(isclass) <- 664500 3.818 5.060 /usr/lib64/python2.6/inspect.py:247(getmembers) /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) 66245 3.731 8.416 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) /usr/share/vdsm/virt/sampling.py:337(AdvancedStatsFunction.interval) <- 13140 0.038 0.038 /usr/lib64/python2.6/threading.py:371(_Event.isSet) 13152 0.036 0.036 /usr/lib64/python2.6/threading.py:481(Thread.run) 26148 0.072 0.072 /usr/share/vdsm/virt/sampling.py:345(AdvancedStatsFunction.__call__) 1276650 3.565 3.565 /usr/share/vdsm/virt/sampling.py:452(VmStatsThread.collect) 1000 0.001 0.001 /usr/share/vdsm/virt/sampling.py:454() /usr/lib/python2.6/site-packages/pthread.py:133(Cond.timedwait) <- 53289/53264 3.586 3.964 /usr/lib/python2.6/site-packages/pthreading.py:115(Condition.wait) /usr/share/vdsm/virt/vm.py:560(_avgLatencyCalc) <- 122704 3.443 3.443 /usr/share/vdsm/virt/vm.py:557(VmStatsThread._getDiskLatency) /usr/lib64/python2.6/json/decoder.py:162(JSONObject) <- 1/0 0.001 0.002 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 29401/13821 3.387 7.744 /usr/lib64/python2.6/json/scanner.py:38(Scanner.iterscan) /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) 66245 3.223 4.377 /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) /usr/share/vdsm/virt/vm.py:2537(Vm._getExtendCandidates) <- 41536 3.199 20.692 /usr/share/vdsm/virt/vm.py:2591(Vm.extendDrivesIfNeeded) /usr/lib/python2.6/site-packages/mom/Monitor.py:53(HostMonitor.collect) <- 8521 1.567 66.198 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 204 0.041 3.418 /usr/lib/python2.6/site-packages/mom/HostMonitor.py:47(HostMonitor.run) 587/0 0.102 4.191 /usr/lib/python2.6/site-packages/mom/Monitor.py:53(HostMonitor.collect) 170 0.028 1.107 /usr/lib64/python2.6/logging/__init__.py:1046(Logger.info) /usr/lib64/python2.6/xml/dom/minidom.py:349(Attr.__init__) <- 282017 2.968 3.079 /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) 12200 0.129 0.134 /usr/lib64/python2.6/xml/dom/minidom.py:692(Element.setAttribute) /usr/lib/python2.6/site-packages/mom/Policy/spark.py:211(Parser.buildState) <- 18049 2.831 5.627 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:187(Parser.parse) 1/0 0.001 0.001 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:211(Parser.buildState) /usr/lib64/python2.6/fnmatch.py:45(filter) <- 126844 2.813 2.965 /usr/lib64/python2.6/glob.py:49(glob1) /usr/lib64/python2.6/xml/dom/minidom.py:305(_get_elements_by_tagName_helper) <- 19/0 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 580046/0 2.283 2.924 /usr/lib64/python2.6/xml/dom/minidom.py:305(_get_elements_by_tagName_helper) 9958 0.516 3.385 /usr/lib64/python2.6/xml/dom/minidom.py:790(Element.getElementsByTagName) 809 0.006 0.065 /usr/lib64/python2.6/xml/dom/minidom.py:1725(Document.getElementsByTagName) /usr/lib64/python2.6/StringIO.py:208(StringIO.write) <- 87200 0.521 1.137 /usr/lib64/python2.6/codecs.py:347(StreamWriter.write) 6 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 85200 0.776 0.933 /usr/lib64/python2.6/xml/dom/minidom.py:299(_write_data) 165194 1.492 1.792 /usr/share/vdsm/virt/vm.py:6012(Element.__hacked_writexml) /usr/lib64/python2.6/glob.py:49(glob1) <- 126851 2.732 9.543 /usr/lib64/python2.6/glob.py:18(iglob) /usr/share/vdsm/virt/vm.py:1943(Vm._get_lastStatus) <- 3143 0.015 0.015 /usr/share/vdsm/API.py:1647(Global._countVms) 8 0.000 0.000 /usr/share/vdsm/virt/guestagent.py:115(GuestAgent.__init__) 85 0.001 0.001 /usr/share/vdsm/virt/vm.py:2263(Vm._startUnderlyingVm) 399 0.002 0.002 /usr/share/vdsm/virt/vm.py:2399(Vm._saveStateInternal) 30593 0.181 0.181 /usr/share/vdsm/virt/vm.py:2855(Vm.status) 66286 0.847 0.847 /usr/share/vdsm/virt/vm.py:2867(Vm.getStats) 132666 1.597 1.597 /usr/share/vdsm/virt/vm.py:2962(Vm._getVmStatus) 3 0.000 0.000 /usr/share/vdsm/virt/vm.py:3192(Vm._getUnderlyingVmDevicesInfo) 88 0.001 0.001 /usr/share/vdsm/virt/vm.py:3252(Vm._domDependentInit) 15 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/lib/python2.6/site-packages/mom/Policy/spark.py:103(Parser.addRule) <- 22905 2.622 2.966 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:127(Parser.collectRules) /usr/lib64/python2.6/logging/__init__.py:1204(Logger.callHandlers) <- 1 0.000 0.000 /usr/lib64/python2.6/logging/__init__.py:566(Logger.filter) 74073 2.569 30.275 /usr/lib64/python2.6/logging/__init__.py:1175(Logger.handle) /usr/share/vdsm/virt/vm.py:611(VmStatsThread.get) <- 66247 2.521 36.193 /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) /usr/lib/python2.6/site-packages/pthreading.py:66(Lock.acquire) <- 3 0.000 0.000 /usr/lib/python2.6/site-packages/mom/GuestMonitor.py:51(GuestMonitor.run) 159561 0.868 2.331 /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) 942 0.003 0.007 /usr/lib64/python2.6/Queue.py:45(Queue.task_done) 942 0.003 0.007 /usr/lib64/python2.6/Queue.py:93(Queue.empty) 15606 0.040 0.103 /usr/lib64/python2.6/Queue.py:107(Queue.put) 23718 0.132 0.355 /usr/lib64/python2.6/Queue.py:150(Queue.get) 3038 0.013 0.037 /usr/lib64/python2.6/logging/__init__.py:194(_acquireLock) 128417 0.516 1.393 /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) 1796 0.009 0.025 /usr/lib64/python2.6/threading.py:310(_Semaphore.acquire) 1793 0.008 0.021 /usr/lib64/python2.6/threading.py:331(_Semaphore.release) 2686 0.011 0.030 /usr/lib64/python2.6/threading.py:376(_Event.set) 2 0.000 0.000 /usr/lib64/python2.6/threading.py:384(_Event.clear) 104871 0.731 1.927 /usr/lib64/python2.6/threading.py:391(_Event.wait) 13639 0.035 0.084 /usr/lib64/python2.6/threading.py:463(Thread.start) 1 0.000 0.000 /usr/lib64/python2.6/threading.py:481(Thread.run) 13600 0.076 0.224 /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) 539 0.002 0.005 /usr/share/vdsm/storage/misc.py:868(OperationMutex.acquire) 306 0.001 0.004 /usr/share/vdsm/storage/resourceManager.py:789(Owner.acquire) 2088 0.009 0.023 /usr/share/vdsm/storage/resourceManager.py:938(Owner.releaseAll) 306 0.001 0.002 /usr/share/vdsm/storage/resourceManager.py:950(Owner.release) 2089 0.009 0.022 /usr/share/vdsm/storage/resourceManager.py:976(Owner.cancelAll) 306 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) 2396 0.012 0.030 /usr/share/vdsm/storage/task.py:975(Task._incref) 2396 0.009 0.025 /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/lib64/python2.6/logging/__init__.py:423(Formatter.format) <- 128915 2.454 6.934 /usr/lib64/python2.6/logging/__init__.py:643(WatchedFileHandler.format) /usr/lib64/python2.6/xml/dom/expatbuilder.py:274(ExpatBuilderNS.character_data_handler_cdata) <- 216574 2.415 3.658 /usr/lib64/python2.6/xml/dom/expatbuilder.py:133(ExpatBuilder) /usr/share/vdsm/API.py:356(VM.getStats) <- 16905 0.776 39.870 /usr/lib/python2.6/site-packages/mom/HypervisorInterfaces/vdsmInterface.py:87(vdsmInterface.getVmMemoryStats) 16905 0.677 36.010 /usr/lib/python2.6/site-packages/mom/HypervisorInterfaces/vdsmInterface.py:142(vdsmInterface.getVmBalloonInfo) 16905 0.648 34.771 /usr/lib/python2.6/site-packages/mom/HypervisorInterfaces/vdsmInterface.py:166(vdsmInterface.getVmCpuTuneInfo) 2953 0.030 1.955 /usr/share/vdsm/API.py:1263(Global.getAllVmStats) 190 0.003 0.214 /usr/share/vdsm/API.py:1346(Global.getVMList) 12566 0.235 12.028 /usr/share/vdsm/rpc/Bridge.py:249(DynamicBridge._dynamicMethod) /usr/lib64/python2.6/logging/__init__.py:764(WatchedFileHandler.emit) <- 54240 0.935 8.806 /usr/lib64/python2.6/logging/__init__.py:666(WatchedFileHandler.handle) 74074 1.392 8.610 /usr/lib64/python2.6/logging/__init__.py:851(WatchedFileHandler.emit) /usr/share/vdsm/virt/vm.py:2962(Vm._getVmStatus) <- 66282 2.244 7.194 /usr/share/vdsm/virt/vm.py:2867(Vm.getStats) 4 0.000 0.001 /usr/share/vdsm/virt/vm.py:2912(Vm._getRunningVmStats) /usr/lib64/python2.6/xml/dom/minidom.py:281(Document._append_child) <- 8100 0.053 0.056 /usr/lib64/python2.6/xml/dom/__init__.py:90(NoDataAllowedErr) 216574 1.157 1.242 /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 1.027 1.074 /usr/lib64/python2.6/xml/dom/expatbuilder.py:743(ExpatBuilderNS.start_element_handler) /usr/lib/python2.6/site-packages/pthreading.py:115(Condition.wait) <- 20909/20886 0.318 1.275 /usr/lib64/python2.6/Queue.py:150(Queue.get) 59308/59304 1.858 5.607 /usr/lib64/python2.6/threading.py:391(_Event.wait) 2 0.000 0.000 /usr/share/vdsm/storage/sdc.py:101(StorageDomainCache._realProduce) /usr/lib64/python2.6/glob.py:77(has_magic) <- 380581 2.114 2.832 /usr/lib64/python2.6/glob.py:18(iglob) /usr/lib64/python2.6/posixpath.py:79(split) <- 126860 2.081 2.416 /usr/lib64/python2.6/glob.py:18(iglob) 107 0.001 0.002 /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/mom/Policy/spark.py:146(Parser.makeFIRST) <- 4581 2.081 2.297 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:187(Parser.parse) /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.001 0.003 /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.001 0.003 /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) 86231 2.103 4.004 /usr/lib64/python2.6/ConfigParser.py:336(ConfigParser._get) 312 0.003 0.006 /usr/lib64/python2.6/ConfigParser.py:348(ConfigParser.getboolean) 100 0.001 0.003 /usr/share/vdsm/API.py:166(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.005 /usr/share/vdsm/storage/threadPool.py:64(ThreadPool.setThreadCount) 1 0.000 0.000 /usr/share/vdsm/virt/vm.py:23() 200 0.003 0.005 /usr/share/vdsm/virt/vm.py:1275(Drive.__init__)