Attachment 898046 Details for Bug 1099856 – profile results sorted by time

profile results sorted by time

nfs-30-domains-spm-time.txt (text/plain), 10.84 KB, created by Nir Soffer on 2014-05-21 15:06:28 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Nir Soffer

Created: 2014-05-21 15:06:28 UTC

Size: 10.84 KB

patch

obsolete

>Wed May 21 17:32:32 2014    nfs-30-domains-spm.prof
>
>         26572533 function calls (26777716 primitive calls) in 458.936 CPU seconds
>
>   Ordered by: internal time
>   List reduced from 1850 to 20 due to restriction <20>
>
>   ncalls  tottime  percall  cumtime  percall filename:lineno(function)
>2405001/2405007   63.372    0.000  103.245    0.000 posixpath.py:311(normpath)
>  1191155   61.104    0.000  263.025    0.000 mount.py:42(_parseFstabLine)
>5833/5908   54.281    0.009  362.630    0.061 threading.py:481(Thread.run)
>10380095/10380125   43.135    0.000   74.448    0.000 mount.py:70(_parseFstabPath)
>307984/308105   15.901    0.000  210.807    0.001 mount.py:78(_resolveLoopDevice)
>     6925    9.060    0.001    9.740    0.001 subprocess.py:509(list2cmdline)
>  1191277    7.880    0.000    8.728    0.000 <string>:8(__new__)
>    94802    7.084    0.000   11.361    0.000 remoteFileHandler.py:133(CrabRPCProxy._poll)
>42502/42508    4.955    0.000   13.295    0.000 remoteFileHandler.py:144(CrabRPCProxy._recvAll)
>    24834    4.850    0.000    5.858    0.000 spark.py:211(Parser.buildState)
>    24606    4.473    0.000    7.117    0.000 __init__.py:226(LogRecord.__init__)
>25637/25649    4.122    0.000    4.340    0.000 pthread.py:133(Cond.timedwait)
>   234484    4.037    0.000    4.037    0.000 pthread.py:95(Lock.lock)
>   315490    3.978    0.000   82.256    0.000 mount.py:115(_iterKnownMounts)
>   258782    3.570    0.000    3.683    0.000 pthread.py:98(Lock.unlock)
>    14100    3.559    0.000   10.451    0.001 misc.py:125(findCaller)
>42505/42508    3.267    0.000    9.867    0.000 remoteFileHandler.py:164(CrabRPCProxy._sendAll)
>315181/315490    3.219    0.000  296.119    0.001 mount.py:121(_iterMountRecords)
>    35095    2.944    0.000    3.380    0.000 spark.py:103(Parser.addRule)
>   145598    2.924    0.000    5.897    0.000 utils.py:557(AsyncProc._processStreams)
>
>
>   Ordered by: internal time
>   List reduced from 1850 to 20 due to restriction <20>
>
>Function                                         was called by...
>                                                     ncalls  tottime  cumtime
>posixpath.py:311(normpath)                       <-     249    0.049    0.051  fileSD.py:97(getDomUuidFromMetafilePath)
>                                                    2382303   62.085  101.729  mount.py:42(_parseFstabLine)
>                                                          1    0.000    0.000  mount.py:70(_parseFstabPath)
>                                                       7324    0.561    0.705  mount.py:148(getMountFromTarget)
>                                                      14804    0.631    0.711  mount.py:167(Mount.__init__)
>                                                        6/0    0.002    0.002  posixpath.py:311(normpath)
>                                                        260    0.045    0.048  posixpath.py:340(abspath)
>                                                         60    0.001    0.001  storageServer.py:301(NFSConnection.__init__)
>mount.py:42(_parseFstabLine)                     <-  883049   44.703  185.169  mount.py:64(_iterateMtab)
>                                                     308105   16.400   77.854  mount.py:115(_iterKnownMounts)
>                                                          1    0.001    0.002  threading.py:481(Thread.run)
>threading.py:481(Thread.run)                     <-     1/0    0.079    0.079  Queue.py:208(Queue._get)
>                                                          1    0.000    0.000  UserDict.py:17(_Environ.__getitem__)
>                                                        1/0    0.003    0.003  __init__.py:43(<genexpr>)
>                                                        1/0    0.006    0.007  __init__.py:66(CPopen._execute_child_v275)
>                                                          1    0.002    0.002  __init__.py:226(LogRecord.__init__)
>                                                        1/0    0.910    0.910  __init__.py:1121(Logger.findCaller)
>                                                        1/0    0.004    0.004  fileSD.py:668(collectMetaFiles)
>                                                        1/0    0.008    0.008  fnmatch.py:45(filter)
>                                                        2/1    0.025    0.025  glob.py:18(iglob)
>                                                          1    0.007    0.007  glob.py:77(has_magic)
>                                                        1/0    0.524    0.524  httplib.py:1158(NotConnected)
>                                                        1/0    0.007    0.007  misc.py:1069(setNonBlocking)
>                                                        4/0   14.486   14.486  mount.py:42(_parseFstabLine)
>                                                        1/0    7.713    7.715  mount.py:64(_iterateMtab)
>                                                        6/0   21.089   21.089  mount.py:70(_parseFstabPath)
>                                                        3/2    0.008    0.009  outOfProcess.py:35(getProcessPool)
>                                                        1/0    0.002    0.002  outOfProcess.py:71(_ModuleWrapper.__getattr__)
>                                                        3/0    0.055    0.055  posixpath.py:59(join)
>                                                          1    0.005    0.005  posixpath.py:117(dirname)
>                                                       10/0   31.710   31.710  posixpath.py:311(normpath)
>                                                        2/1    0.113    0.113  pthread.py:98(Lock.unlock)
>                                                        1/0    0.650    0.650  pthread.py:129(Cond.wait)
>                                                        1/0    0.035    0.035  pthreading.py:66(Lock.acquire)
>                                                        1/0    0.044    0.044  pthreading.py:77(Lock.release)
>                                                        2/0    0.089    0.089  pthreading.py:92(Condition.__init__)
>                                                          1    0.008    0.008  pthreading.py:122(Condition.notifyAll)
>                                                        7/1    0.048    0.051  remoteFileHandler.py:133(CrabRPCProxy._poll)
>                                                        4/1    0.030    0.031  remoteFileHandler.py:144(CrabRPCProxy._recvAll)
>                                                        5/2    0.032    0.035  remoteFileHandler.py:164(CrabRPCProxy._sendAll)
>                                                        1/0    0.006    0.006  remoteFileHandler.py:178(CrabRPCProxy.callCrabRPCFunction)
>                                                        3/0    0.156    0.156  threading.py:446(Thread._set_daemon)
>                                                        2/0    0.060    0.061  threading.py:463(Thread.start)
>                                                        2/0    0.011    0.011  utils.py:401(NoIntrPoll)
>                                                        2/0    0.000    0.005  utils.py:750(wrapper)
>mount.py:70(_parseFstabPath)                     <- 10380025   43.131   74.444  mount.py:42(_parseFstabLine)
>                                                       30/0    0.001    0.002  mount.py:70(_parseFstabPath)
>                                                         63    0.003    0.003  posixpath.py:311(normpath)
>                                                          7    0.000    0.000  threading.py:481(Thread.run)
>mount.py:78(_resolveLoopDevice)                  <-       3    0.006    0.082  mount.py:115(_iterKnownMounts)
>                                                  308102/307981   15.895  210.725  mount.py:121(_iterMountRecords)
>subprocess.py:509(list2cmdline)                  <-    6925    9.060    9.740  utils.py:646(execCmd)
><string>:8(__new__)                              <-      60    0.000    0.000  hsm.py:163(_connectionDict2ConnectionInfo)
>remoteFileHandler.py:133(CrabRPCProxy._poll)     <-   94802    7.084   11.361  contextlib.py:81(helper)
>remoteFileHandler.py:144(CrabRPCProxy._recvAll)  <-     6/0    0.002    0.007  remoteFileHandler.py:144(CrabRPCProxy._recvAll)
>                                                         14    0.023    0.040  remoteFileHandler.py:164(CrabRPCProxy._sendAll)
>                                                      42486    4.929   13.253  remoteFileHandler.py:178(CrabRPCProxy.callCrabRPCFunction)
>                                                          2    0.001    0.002  threading.py:481(Thread.run)
>spark.py:211(Parser.buildState)                  <-   24834    4.850    5.858  spark.py:187(Parser.parse)
>__init__.py:226(LogRecord.__init__)              <-   24606    4.473    7.117  __init__.py:1142(Logger.makeRecord)
>pthread.py:133(Cond.timedwait)                   <- 25649/25637    4.122    4.340  pthreading.py:105(Condition.wait)
>pthread.py:95(Lock.lock)                         <-  234484    4.037    4.037  pthreading.py:66(Lock.acquire)
>mount.py:115(_iterKnownMounts)                   <-  315490    3.978   82.256  mount.py:121(_iterMountRecords)
>pthread.py:98(Lock.unlock)                       <-  258781    3.570    3.683  pthreading.py:77(Lock.release)
>                                                          1    0.000    0.000  threading.py:481(Thread.run)
>misc.py:125(findCaller)                          <-   13811    3.476   10.219  misc.py:104(Logger.<lambda>)
>                                                        289    0.083    0.232  resourceManager.py:178(Request.__init__)
>remoteFileHandler.py:164(CrabRPCProxy._sendAll)  <-     3/0    0.001    0.003  remoteFileHandler.py:164(CrabRPCProxy._sendAll)
>                                                      42505    3.266    9.867  remoteFileHandler.py:178(CrabRPCProxy.callCrabRPCFunction)
>mount.py:121(_iterMountRecords)                  <-    27/0    0.003    0.488  mount.py:64(_iterateMtab)
>                                                       56/0    0.003    0.215  mount.py:78(_resolveLoopDevice)
>                                                      226/0    0.028    1.299  mount.py:121(_iterMountRecords)
>                                                         27    0.000    0.003  mount.py:132(iterMounts)
>                                                     312634    3.181  295.825  mount.py:148(getMountFromTarget)
>                                                       2520    0.004    0.292  mount.py:239(Mount.getRecord)
>spark.py:103(Parser.addRule)                     <-   35095    2.944    3.380  spark.py:127(Parser.collectRules)
>utils.py:557(AsyncProc._processStreams)          <-    6862    1.452    2.735  utils.py:440(_streamWrapper.close)
>                                                       6730    0.790    1.477  utils.py:466(_streamWrapper._readNonBlock)
>                                                       1008    0.133    0.284  utils.py:501(_streamWrapper.write)
>                                                     130998    0.549    1.401  utils.py:623(AsyncProc.wait)
>
>

Actions: View

Attachments on bug 1099856: 897917 | 897918 | 897920 | 898046 | 898047