Attachment 892044 Details for Bug 1090664 – profile results when memory filesystems support is disabled, sorted by time

profile results when memory filesystems support is disabled, sorted by time

memory-filesystems-disabled-time.txt (text/plain), 28.82 KB, created by Nir Soffer on 2014-05-02 22:06:20 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Nir Soffer

Created: 2014-05-02 22:06:20 UTC

Size: 28.82 KB

patch

obsolete

>Fri May  2 16:37:38 2014    vdsmd.prof
>
>         47374883 function calls (47747891 primitive calls) in 1000.094 seconds
>
>   Ordered by: internal time
>   List reduced from 2392 to 20 due to restriction <20>
>
>   ncalls  tottime  percall  cumtime  percall filename:lineno(function)
>   637148   62.731    0.000  397.876    0.001 /usr/lib64/python2.6/io.py:492(BufferedReader.readline)
>  1831830   50.597    0.000   63.185    0.000 /usr/lib64/python2.6/posixpath.py:311(normpath)
>   914090   41.288    0.000  151.291    0.000 /usr/share/vdsm/storage/mount.py:42(_parseFstabLine)
>  2199364   37.998    0.000   37.998    0.000 /usr/lib/python2.6/site-packages/pthread.py:98(Lock.unlock)
>  2070142   36.258    0.000   36.258    0.000 /usr/lib/python2.6/site-packages/pthread.py:95(Lock.lock)
>    23177   33.057    0.001   36.764    0.002 /usr/lib64/python2.6/subprocess.py:509(list2cmdline)
>   718952   28.007    0.000  104.623    0.000 /usr/lib64/python2.6/io.py:998(BufferedReader._peek_unlocked)
>  2200406   25.460    0.000   63.981    0.000 /usr/lib/python2.6/site-packages/pthreading.py:66(Lock.acquire)
>  4373765   24.514    0.000   37.083    0.000 /usr/share/vdsm/storage/mount.py:70(_parseFstabPath)
>   718952   23.752    0.000   41.506    0.000 /usr/lib64/python2.6/io.py:942(BufferedReader._read_unlocked)
>   303068   22.312    0.000   44.492    0.000 /usr/lib64/python2.6/site-packages/vdsm/utils.py:386(AsyncProc._processStreams)
>   718952   21.347    0.000  180.910    0.000 /usr/lib64/python2.6/io.py:988(BufferedReader.peek)
>66885/66886   21.218    0.000   27.659    0.000 /usr/lib/python2.6/site-packages/mom/Policy/spark.py:211(Parser.buildState)
>   718952   21.148    0.000  117.019    0.000 /usr/lib64/python2.6/io.py:931(BufferedReader.read)
>   240159   19.667    0.000   91.776    0.000 /usr/lib64/python2.6/site-packages/vdsm/utils.py:308(_streamWrapper.read)
>  2199364   19.531    0.000   57.896    0.000 /usr/lib/python2.6/site-packages/pthreading.py:77(Lock.release)
>   718952   19.291    0.000  201.264    0.000 /usr/lib64/python2.6/io.py:503(nreadahead)
>  1910542   17.159    0.000   73.842    0.000 /usr/lib/python2.6/site-packages/pthreading.py:59(Lock.__enter__)
>  1910541   16.717    0.000   67.286    0.000 /usr/lib/python2.6/site-packages/pthreading.py:63(Lock.__exit__)
>    50760   14.010    0.000   48.275    0.001 /usr/share/vdsm/storage/storage_exception.py:149(MiscBlockWriteIncomplete)
>
>
>   Ordered by: internal time
>   List reduced from 2392 to 20 due to restriction <20>
>
>Function                                                                         was called by...
>                                                                                     ncalls  tottime  cumtime
>/usr/lib64/python2.6/io.py:492(BufferedReader.readline)                          <-  637147   62.731  397.875  /usr/lib64/python2.6/io.py:532(BufferedReader.next)
>                                                                                          1    0.000    0.001  /usr/lib64/python2.6/threading.py:481(Thread.run)
>/usr/lib64/python2.6/posixpath.py:311(normpath)                                  <-     512    0.006    0.010  /usr/lib64/python2.6/posixpath.py:340(abspath)
>                                                                                         24    0.002    0.002  /usr/share/vdsm/storage/fileSD.py:84(getDomUuidFromMetafilePath)
>                                                                                    1828180   50.432   62.976  /usr/share/vdsm/storage/mount.py:42(_parseFstabLine)
>                                                                                       1413    0.115    0.143  /usr/share/vdsm/storage/mount.py:148(getMountFromTarget)
>                                                                                       1700    0.042    0.054  /usr/share/vdsm/storage/mount.py:191(Mount.__init__)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/storageServer.py:295(NFSConnection.__init__)
>/usr/share/vdsm/storage/mount.py:42(_parseFstabLine)                             <-  735800   31.513  115.984  /usr/share/vdsm/storage/mount.py:64(_iterateMtab)
>                                                                                     178290    9.775   35.307  /usr/share/vdsm/storage/mount.py:115(_iterKnownMounts)
>/usr/lib/python2.6/site-packages/pthread.py:98(Lock.unlock)                      <- 2199363   37.998   37.998  /usr/lib/python2.6/site-packages/pthreading.py:77(Lock.release)
>                                                                                          1    0.000    0.000  /usr/lib64/python2.6/threading.py:481(Thread.run)
>/usr/lib/python2.6/site-packages/pthread.py:95(Lock.lock)                        <- 2070142   36.258   36.258  /usr/lib/python2.6/site-packages/pthreading.py:66(Lock.acquire)
>/usr/lib64/python2.6/subprocess.py:509(list2cmdline)                             <-   23177   33.057   36.764  /usr/lib64/python2.6/site-packages/vdsm/utils.py:475(execCmd)
>/usr/lib64/python2.6/io.py:998(BufferedReader._peek_unlocked)                    <-  718952   28.007  104.623  /usr/lib64/python2.6/io.py:988(BufferedReader.peek)
>/usr/lib/python2.6/site-packages/pthreading.py:66(Lock.acquire)                  <- 1910542   22.530   56.307  /usr/lib/python2.6/site-packages/pthreading.py:59(Lock.__enter__)
>                                                                                      72120    0.698    2.000  /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__)
>                                                                                     126020    1.455    3.623  /usr/lib64/python2.6/site-packages/vdsm/utils.py:386(AsyncProc._processStreams)
>                                                                                       7320    0.068    0.199  /usr/lib64/python2.6/threading.py:310(_Semaphore.acquire)
>                                                                                       7320    0.045    0.118  /usr/lib64/python2.6/threading.py:331(_Semaphore.release)
>                                                                                        111    0.002    0.005  /usr/lib64/python2.6/threading.py:376(_Event.set)
>                                                                                          2    0.000    0.000  /usr/lib64/python2.6/threading.py:384(_Event.clear)
>                                                                                      28234    0.245    0.701  /usr/lib64/python2.6/threading.py:391(_Event.wait)
>                                                                                          1    0.000    0.000  /usr/lib64/python2.6/threading.py:481(Thread.run)
>                                                                                       2899    0.017    0.047  /usr/lib64/python2.6/threading.py:588(Thread.__stop)
>                                                                                         13    0.000    0.001  /usr/lib64/python2.6/threading.py:629(WorkerThread.join)
>                                                                                       1520    0.010    0.029  /usr/share/vdsm/storage/remoteFileHandler.py:286(RemoteFileHandlerPool.callCrabRPCFunction)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:788(Owner.acquire)
>                                                                                        471    0.002    0.006  /usr/share/vdsm/storage/resourceManager.py:937(Owner.releaseAll)
>                                                                                        471    0.002    0.005  /usr/share/vdsm/storage/resourceManager.py:975(Owner.cancelAll)
>                                                                                         11    0.000    0.000  /usr/share/vdsm/storage/storage_exception.py:366(ImageDeleteError)
>                                                                                          5    0.000    0.000  /usr/share/vdsm/storage/storage_exception.py:380(ImageIsEmpty_deprecated_vdsm23)
>                                                                                       2718    0.056    0.132  /usr/share/vdsm/storage/storage_exception.py:524(GetIsoListError)
>                                                                                         98    0.002    0.005  /usr/share/vdsm/storage/storage_exception.py:708(BlockStorageDomainMasterFSCKError)
>                                                                                         98    0.002    0.005  /usr/share/vdsm/storage/storage_exception.py:742(GetStorageDomainListError)
>                                                                                        127    0.002    0.006  /usr/share/vdsm/storage/storage_exception.py:752(StorageDomainNotMemberOfPool)
>                                                                                      39354    0.318    0.779  /usr/share/vdsm/storage/storage_exception.py:764(StorageDomainCheckError)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/task.py:804(Task.resourceAcquired)
>                                                                                        473    0.003    0.007  /usr/share/vdsm/storage/task.py:956(Task._incref)
>                                                                                        472    0.002    0.006  /usr/share/vdsm/storage/task.py:968(Task._decref)
>                                                                                          2    0.000    0.000  /usr/share/vdsm/storage/threadPool.py:64(ThreadPool.setThreadCount)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/threadPool.py:141(ThreadPool.joinAll)
>/usr/share/vdsm/storage/mount.py:70(_parseFstabPath)                             <- 4373765   24.514   37.083  /usr/share/vdsm/storage/mount.py:42(_parseFstabLine)
>/usr/lib64/python2.6/io.py:942(BufferedReader._read_unlocked)                    <-  718952   23.752   41.506  /usr/lib64/python2.6/io.py:931(BufferedReader.read)
>/usr/lib64/python2.6/site-packages/vdsm/utils.py:386(AsyncProc._processStreams)  <-   23177    5.951   11.280  /usr/lib64/python2.6/site-packages/vdsm/utils.py:282(_streamWrapper.close)
>                                                                                      69853   13.394   25.886  /usr/lib64/python2.6/site-packages/vdsm/utils.py:308(_streamWrapper.read)
>                                                                                          8    0.001    0.002  /usr/lib64/python2.6/site-packages/vdsm/utils.py:332(_streamWrapper.write)
>                                                                                     210030    2.966    7.323  /usr/lib64/python2.6/site-packages/vdsm/utils.py:452(AsyncProc.wait)
>/usr/lib64/python2.6/io.py:988(BufferedReader.peek)                              <-  718952   21.347  180.910  /usr/lib64/python2.6/io.py:503(nreadahead)
>/usr/lib/python2.6/site-packages/mom/Policy/spark.py:211(Parser.buildState)      <-       3    0.001    0.001  /usr/lib/python2.6/site-packages/mom/Policy/spark.py:146(Parser.makeFIRST)
>                                                                                      66882   21.213   27.658  /usr/lib/python2.6/site-packages/mom/Policy/spark.py:187(Parser.parse)
>                                                                                        1/0    0.004    0.004  /usr/lib/python2.6/site-packages/mom/Policy/spark.py:211(Parser.buildState)
>/usr/lib64/python2.6/io.py:931(BufferedReader.read)                              <-  718952   21.148  117.019  /usr/lib64/python2.6/io.py:492(BufferedReader.readline)
>/usr/lib64/python2.6/site-packages/vdsm/utils.py:308(_streamWrapper.read)        <-   67268    5.259   16.914  /usr/lib64/python2.6/io.py:942(BufferedReader._read_unlocked)
>                                                                                     172891   14.408   74.862  /usr/lib64/python2.6/io.py:998(BufferedReader._peek_unlocked)
>/usr/lib/python2.6/site-packages/pthreading.py:77(Lock.release)                  <- 1910541   17.445   50.569  /usr/lib/python2.6/site-packages/pthreading.py:63(Lock.__exit__)
>                                                                                      72121    0.185    1.682  /usr/lib64/python2.6/logging/__init__.py:630(WatchedFileHandler.release)
>                                                                                          1    0.000    0.000  /usr/lib64/python2.6/multiprocessing/managers.py:666(AutoProxy[instance].__init__)
>                                                                                     126020    1.097    3.316  /usr/lib64/python2.6/site-packages/vdsm/utils.py:386(AsyncProc._processStreams)
>                                                                                       7320    0.048    0.122  /usr/lib64/python2.6/threading.py:310(_Semaphore.acquire)
>                                                                                       7320    0.039    0.099  /usr/lib64/python2.6/threading.py:331(_Semaphore.release)
>                                                                                        111    0.002    0.005  /usr/lib64/python2.6/threading.py:376(_Event.set)
>                                                                                          2    0.000    0.000  /usr/lib64/python2.6/threading.py:384(_Event.clear)
>                                                                                      28210    0.395    1.093  /usr/lib64/python2.6/threading.py:391(_Event.wait)
>                                                                                       2899    0.013    0.036  /usr/lib64/python2.6/threading.py:588(Thread.__stop)
>                                                                                         12    0.000    0.000  /usr/lib64/python2.6/threading.py:629(WorkerThread.join)
>                                                                                       1520    0.007    0.022  /usr/share/vdsm/storage/remoteFileHandler.py:286(RemoteFileHandlerPool.callCrabRPCFunction)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:788(Owner.acquire)
>                                                                                        471    0.002    0.005  /usr/share/vdsm/storage/resourceManager.py:937(Owner.releaseAll)
>                                                                                        471    0.002    0.005  /usr/share/vdsm/storage/resourceManager.py:975(Owner.cancelAll)
>                                                                                          5    0.000    0.000  /usr/share/vdsm/storage/storage_exception.py:366(ImageDeleteError)
>                                                                                         10    0.000    0.000  /usr/share/vdsm/storage/storage_exception.py:380(ImageIsEmpty_deprecated_vdsm23)
>                                                                                       1713    0.012    0.039  /usr/share/vdsm/storage/storage_exception.py:529(GetFloppyListError)
>                                                                                         98    0.001    0.004  /usr/share/vdsm/storage/storage_exception.py:708(BlockStorageDomainMasterFSCKError)
>                                                                                         98    0.001    0.005  /usr/share/vdsm/storage/storage_exception.py:742(GetStorageDomainListError)
>                                                                                        127    0.002    0.005  /usr/share/vdsm/storage/storage_exception.py:752(StorageDomainNotMemberOfPool)
>                                                                                      39344    0.277    0.877  /usr/share/vdsm/storage/storage_exception.py:764(StorageDomainCheckError)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/task.py:804(Task.resourceAcquired)
>                                                                                        473    0.002    0.006  /usr/share/vdsm/storage/task.py:956(Task._incref)
>                                                                                        472    0.002    0.005  /usr/share/vdsm/storage/task.py:968(Task._decref)
>                                                                                          2    0.000    0.000  /usr/share/vdsm/storage/threadPool.py:64(ThreadPool.setThreadCount)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/threadPool.py:141(ThreadPool.joinAll)
>/usr/lib64/python2.6/io.py:503(nreadahead)                                       <-  718952   19.291  201.264  /usr/lib64/python2.6/io.py:492(BufferedReader.readline)
>/usr/lib/python2.6/site-packages/pthreading.py:59(Lock.__enter__)                <-  132573    0.748    4.662  /usr/lib/python2.6/site-packages/pthreading.py:99(Condition.__enter__)
>                                                                                     718952    6.758   28.286  /usr/lib64/python2.6/io.py:931(BufferedReader.read)
>                                                                                     718952    6.729   28.714  /usr/lib64/python2.6/io.py:988(BufferedReader.peek)
>                                                                                          2    0.000    0.000  /usr/lib64/python2.6/io.py:1053(BufferedWriter.write)
>                                                                                      23179    0.352    1.216  /usr/lib64/python2.6/io.py:1091(BufferedWriter.flush)
>                                                                                          3    0.000    0.000  /usr/lib64/python2.6/logging/__init__.py:1034(Logger.debug)
>                                                                                         35    0.001    0.003  /usr/lib64/python2.6/site-packages/vdsm/libvirtconnection.py:64(get)
>                                                                                     240159    2.014    8.438  /usr/lib64/python2.6/site-packages/vdsm/utils.py:308(_streamWrapper.read)
>                                                                                          2    0.000    0.000  /usr/lib64/python2.6/site-packages/vdsm/utils.py:332(_streamWrapper.write)
>                                                                                         30    0.001    0.003  /usr/share/vdsm/clientIF.py:114(clientIF.contEIOVms)
>                                                                                       2801    0.014    0.075  /usr/share/vdsm/clientIF.py:136(getInstance)
>                                                                                         29    0.001    0.005  /usr/share/vdsm/storage/clusterlock.py:171(SANLock.acquireHostId)
>                                                                                          8    0.000    0.000  /usr/share/vdsm/storage/clusterlock.py:194(SANLock.releaseHostId)
>                                                                                      20217    0.142    0.657  /usr/share/vdsm/storage/clusterlock.py:209(SANLock.hasHostId)
>                                                                                         30    0.000    0.000  /usr/share/vdsm/storage/iscsi.py:146(addIscsiNode)
>                                                                                         92    0.000    0.001  /usr/share/vdsm/storage/iscsiadm.py:74(_runCmd)
>                                                                                          3    0.000    0.000  /usr/share/vdsm/storage/lvm.py:259(LVMCache._getCachedExtraCfg)
>                                                                                      44675    0.317    1.427  /usr/share/vdsm/storage/persistentDict.py:148(PersistentDict._accessWrapper)
>                                                                                       1417    0.013    0.056  /usr/share/vdsm/storage/persistentDict.py:228(PersistentDict.refresh)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/persistentDict.py:305(PersistentDict.invalidate)
>                                                                                          4    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:229(Request.grant)
>                                                                                          4    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:251(Request.granted)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:389(getInstance)
>                                                                                          4    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:526(ResourceManager.registerResource)
>                                                                                          2    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:608(ResourceManager.releaseResource)
>                                                                                          3    0.000    0.000  /usr/share/vdsm/storage/storage_exception.py:115(NotImplementedException)
>                                                                                         98    0.002    0.006  /usr/share/vdsm/storage/storage_exception.py:312(createVolumeSizeError)
>                                                                                         98    0.001    0.007  /usr/share/vdsm/storage/storage_exception.py:317(VolumeWasNotPreparedBeforeTeardown)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/storage_exception.py:482(ReconstructMasterError)
>                                                                                         27    0.001    0.003  /usr/share/vdsm/storage/storage_exception.py:492(StoragePoolDestroyingError)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/supervdsm.py:90(getProxy)
>                                                                                       7139    0.066    0.282  /usr/share/vdsm/vmChannels.py:115(Listener._update_channels)
>/usr/lib/python2.6/site-packages/pthreading.py:63(Lock.__exit__)                 <-  132573    0.953    3.811  /usr/lib/python2.6/site-packages/pthreading.py:102(Condition.__exit__)
>                                                                                     718952    6.470   26.079  /usr/lib64/python2.6/io.py:931(BufferedReader.read)
>                                                                                     718952    6.482   26.226  /usr/lib64/python2.6/io.py:988(BufferedReader.peek)
>                                                                                          2    0.000    0.000  /usr/lib64/python2.6/io.py:1053(BufferedWriter.write)
>                                                                                      23179    0.321    1.054  /usr/lib64/python2.6/io.py:1091(BufferedWriter.flush)
>                                                                                          4    0.000    0.000  /usr/lib64/python2.6/logging/__init__.py:1034(Logger.debug)
>                                                                                          4    0.000    0.000  /usr/lib64/python2.6/logging/__init__.py:1046(Logger.info)
>                                                                                         35    0.001    0.003  /usr/lib64/python2.6/site-packages/vdsm/libvirtconnection.py:64(get)
>                                                                                     240159    1.912    7.820  /usr/lib64/python2.6/site-packages/vdsm/utils.py:308(_streamWrapper.read)
>                                                                                          2    0.000    0.000  /usr/lib64/python2.6/site-packages/vdsm/utils.py:332(_streamWrapper.write)
>                                                                                         30    0.000    0.002  /usr/share/vdsm/clientIF.py:114(clientIF.contEIOVms)
>                                                                                       2801    0.014    0.049  /usr/share/vdsm/clientIF.py:136(getInstance)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/blockSD.py:277(VGTagMetadataRW.readlines)
>                                                                                         24    0.000    0.001  /usr/share/vdsm/storage/clusterlock.py:171(SANLock.acquireHostId)
>                                                                                          7    0.000    0.000  /usr/share/vdsm/storage/clusterlock.py:194(SANLock.releaseHostId)
>                                                                                      20217    0.187    0.760  /usr/share/vdsm/storage/clusterlock.py:209(SANLock.hasHostId)
>                                                                                         30    0.000    0.000  /usr/share/vdsm/storage/iscsi.py:146(addIscsiNode)
>                                                                                         92    0.000    0.001  /usr/share/vdsm/storage/iscsiadm.py:74(_runCmd)
>                                                                                          3    0.000    0.000  /usr/share/vdsm/storage/lvm.py:259(LVMCache._getCachedExtraCfg)
>                                                                                      44675    0.315    1.245  /usr/share/vdsm/storage/persistentDict.py:148(PersistentDict._accessWrapper)
>                                                                                       1416    0.013    0.059  /usr/share/vdsm/storage/persistentDict.py:228(PersistentDict.refresh)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/persistentDict.py:305(PersistentDict.invalidate)
>                                                                                          4    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:229(Request.grant)
>                                                                                          4    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:251(Request.granted)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:389(getInstance)
>                                                                                          4    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:526(ResourceManager.registerResource)
>                                                                                          2    0.000    0.000  /usr/share/vdsm/storage/resourceManager.py:608(ResourceManager.releaseResource)
>                                                                                          3    0.000    0.000  /usr/share/vdsm/storage/storage_exception.py:115(NotImplementedException)
>                                                                                         98    0.001    0.006  /usr/share/vdsm/storage/storage_exception.py:312(createVolumeSizeError)
>                                                                                         98    0.001    0.006  /usr/share/vdsm/storage/storage_exception.py:317(VolumeWasNotPreparedBeforeTeardown)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/storage_exception.py:482(ReconstructMasterError)
>                                                                                         27    0.001    0.003  /usr/share/vdsm/storage/storage_exception.py:492(StoragePoolDestroyingError)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/supervdsm.py:90(getProxy)
>                                                                                       7139    0.043    0.160  /usr/share/vdsm/vmChannels.py:115(Listener._update_channels)
>/usr/share/vdsm/storage/storage_exception.py:149(MiscBlockWriteIncomplete)       <-       4    0.000    0.001  /usr/share/vdsm/storage/resourceManager.py:177(Request.__init__)
>                                                                                          1    0.000    0.000  /usr/share/vdsm/storage/storage_exception.py:34(<module>)
>                                                                                      50755   14.009   48.273  /usr/share/vdsm/storage/storage_exception.py:130(MiscBlockReadException)
>
>

Actions: View

Attachments on bug 1090664: 891081 | 891082 | 892044 | 892045 | 892046 | 892049