Submitted by : simon at: 2005-07-28T22:07:20+00:00 (12 years ago)
Name :
Category : Severity : Status :
Optional subject :  
Optional comment :

Moved from zopewiki

There is a speed problem right now (seen on some other ZWiki sites): switching to simple or full mode makes page viewing speed variable and slower than it should be. The skin does no obvious expensive operations.

Possible tools, techniques:

Some things to stare at:

  $ ab -c1 -n50 http://localhost:8080/zopewiki/TestPage?zwiki_displaymode=minimal
  This is ApacheBench, Version 1.3d <$Revision: 1.70 $> apache-1.3
  Copyright (c) 1996 Adam Twiss, Zeus Technology Ltd, http://www.zeustech.net/
  Copyright (c) 1998-2002 The Apache Software Foundation, http://www.apache.org/

  Benchmarking localhost (be patient).....done
  Server Software:        Zope/(Zope
  Server Hostname:        localhost
  Server Port:            8080

  Document Path:          /zopewiki/TestPage?zwiki_displaymode=minimal
  Document Length:        7464 bytes

  Concurrency Level:      1
  Time taken for tests:   8.116 seconds
  Complete requests:      50
  Failed requests:        0
  Broken pipe errors:     0
  Total transferred:      383550 bytes
  HTML transferred:       373200 bytes
  Requests per second:    6.16 [#/sec] (mean)
  Time per request:       162.32 [ms] (mean)
  Time per request:       162.32 [ms] (mean, across all concurrent requests)
  Transfer rate:          47.26 [Kbytes/sec] received

  Connnection Times (ms)
                min  mean[+/-sd] median   max
  Connect:        0     0    0.0      0     0
  Processing:   133   162   23.0    158   223
  Waiting:      133   162   23.1    157   223
  Total:        133   162   23.0    158   223

  Percentage of the requests served within a certain time (ms)
    50%    158
    66%    168
    75%    174
    80%    178
    90%    202
    95%    217
    98%    222
    99%    223
   100%    223 (last request)

  $ ab -c1 -n50 http://localhost:8080/zopewiki/TestPage?zwiki_displaymode=simple
  This is ApacheBench, Version 1.3d <$Revision: 1.70 $> apache-1.3
  Copyright (c) 1996 Adam Twiss, Zeus Technology Ltd, http://www.zeustech.net/
  Copyright (c) 1998-2002 The Apache Software Foundation, http://www.apache.org/

  Benchmarking localhost (be patient).....done
  Server Software:        Zope/(Zope
  Server Hostname:        localhost
  Server Port:            8080

  Document Path:          /zopewiki/TestPage?zwiki_displaymode=simple
  Document Length:        8784 bytes

  Concurrency Level:      1
  Time taken for tests:   87.885 seconds
  Complete requests:      50
  Failed requests:        47
     (Connect: 0, Length: 47, Exceptions: 0)
  Broken pipe errors:     0
  Total transferred:      450695 bytes
  HTML transferred:       440345 bytes
  Requests per second:    0.57 [#/sec] (mean)
  Time per request:       1757.70 [ms] (mean)
  Time per request:       1757.70 [ms] (mean, across all concurrent requests)
  Transfer rate:          5.13 [Kbytes/sec] received

  Connnection Times (ms)
                min  mean[+/-sd] median   max
  Connect:        0     0    0.0      0     0
  Processing:  1468  1758  492.4   1577  3739
  Waiting:     1467  1757  492.5   1577  3739
  Total:       1468  1758  492.4   1577  3739

  Percentage of the requests served within a certain time (ms)
    50%   1577
    66%   1624
    75%   1681
    80%   1822
    90%   2288
    95%   2942
    98%   3473
    99%   3739
   100%   3739 (last request)

Not simultaneous with the above:

         1107049 function calls (983924 primitive calls) in 58.000 CPU seconds

   Ordered by: cumulative time
   List reduced from 1479 to 200 due to restriction <200>

   ncalls  tottime  percall  cumtime  percall filename:lineno(function)
       45    0.000    0.000   58.000    1.289 Publish.py:147(publish_module_standard)
       45    0.000    0.000   58.000    1.289 Publish.py:363(publish_module)
       45    0.000    0.000   58.000    1.289 profile:0(<function publish_module at 0x4064764c>)
       45    0.000    0.000   57.960    1.288 __init__.py:55(new_publish)
       45    0.000    0.000   57.960    1.288 Publish.py:50(publish)
       43    0.000    0.000   48.260    1.122 mapply.py:32(mapply)
       43    0.000    0.000   48.260    1.122 Publish.py:39(call_object)
    15/13    0.000    0.000   31.950    2.458 ZWikiPage.py:231(__call__)
    15/13    0.010    0.001   31.930    2.456 PageTypes.py:258(render)
    15/13    0.000    0.000   31.930    2.456 ZWikiPage.py:248(render)
    59/22    0.030    0.001   23.170    1.053 Bindings.py:324(_bindAndExec)
    35/22    0.000    0.000   23.170    1.053 Bindings.py:304(__call__)
       11    0.970    0.088   20.230    1.839 ZWikiPage.py:762(pages)
    97/34    0.070    0.001   17.480    0.514 DT_Let.py:70(render)
1154/1147    0.140    0.000   17.050    0.015 DT_Util.py:175(eval)
    24/23    0.010    0.000   16.540    0.719 DT_String.py:361(__call__)
    13227    0.610    0.000   15.760    0.001 ZWikiPage.py:798(<lambda>)
        8    0.490    0.061   15.340    1.918 ZWikiPage.py:849(pageIds)
    13227    1.440    0.000   15.150    0.001 Utils.py:137(ensureCompleteMetadataIn)
    15/14    0.010    0.001   14.180    1.013 UI.py:187(addSkinTo)
    18/13    0.020    0.001   14.160    1.089 ZopePageTemplate.py:192(_exec)
    25/13    0.010    0.000   14.150    1.088 PageTemplate.py:80(pt_render)
    25/13    0.000    0.000   13.520    1.040 TALInterpreter.py:185(__call__)
   552/13    0.130    0.000   13.520    1.040 TALInterpreter.py:218(interpret)
     1152    1.570    0.001   12.750    0.011 Utils.py:112(metadataFor)
1692/1485    0.060    0.000   12.700    0.009 TALES.py:214(evaluate)
    27648    0.860    0.000   11.060    0.000 Common.py:65(absattr)
   122/97    0.010    0.000   10.740    0.111 TALInterpreter.py:645(do_useMacro)
    73/56    0.000    0.000    9.190    0.164 TALInterpreter.py:402(do_optTag_tal)
    73/56    0.010    0.000    9.190    0.164 TALInterpreter.py:382(no_tag)
     33/9    0.000    0.000    9.000    1.000 special_dtml.py:111(_exec)
  812/752    0.020    0.000    8.750    0.012 Expressions.py:198(__call__)
  812/752    0.050    0.000    8.730    0.012 Expressions.py:176(_eval)
       14    0.000    0.000    8.710    0.622 DTMLMethod.py:98(__call__)
  386/136    0.030    0.000    8.630    0.063 TALInterpreter.py:627(do_condition)
        2    0.000    0.000    8.610    4.305 startup.py:138(zpublisher_exception_hook)
        2    0.000    0.000    8.610    4.305 SimpleItem.py:142(raise_standardErrorMessage)
  761/701    0.060    0.000    8.390    0.012 Expressions.py:89(render)
    47/40    0.010    0.000    8.180    0.205 <string>:1(<expression>)
     1234    0.080    0.000    8.040    0.007 <string>:0(?)
    15/14    0.010    0.001    7.700    0.550 ZWikiPage.py:291(evaluatePreRenderedAsDtml)
      7/6    0.010    0.001    7.690    1.282 DTMLDocument.py:98(__call__)
        7    0.010    0.001    7.640    1.091 ZopeProfiler.py:175(getStatistics)
        6    0.120    0.020    7.530    1.255 Tracker.py:133(issueCount)
        4    0.000    0.000    7.190    1.797 PageTypes.py:196(renderStxIn)
        1    0.000    0.000    7.150    7.150 Editing.py:124(comment)
        3    0.000    0.000    7.070    2.357 Tracker.py:141(addIssueFormTo)
  28204/4    3.300    0.000    6.930    1.732 DocumentClass.py:365(color_text)
        4    0.000    0.000    6.930    1.732 DocumentClass.py:307(__call__)
      8/4    0.000    0.000    6.930    1.732 DocumentClass.py:398(color_paragraphs)
        3    0.000    0.000    6.920    2.307 ZWikiPage.py:1054(stxToHtml)
        1    0.000    0.000    6.710    6.710 CatalogAwareness.py:116(index_object)
        1    0.000    0.000    6.710    6.710 CatalogAwareness.py:95(updateCatalog)
        1    0.000    0.000    6.700    6.700 ZCatalog.py:498(catalog_object)
        1    0.000    0.000    6.700    6.700 Catalog.py:334(catalogObject)
      880    0.110    0.000    6.010    0.007 Connection.py:544(setstate)
        4    0.000    0.000    5.680    1.420 Tracker.py:137(hasIssues)
        3    0.070    0.023    5.640    1.880 ZWikiPage.py:842(pageCount)
        2    0.000    0.000    5.340    2.670 TextIndexNG.py:212(index_object)
        2    0.000    0.000    5.340    2.670 TextIndexNG.py:226(_index_object)
        7    0.030    0.004    4.940    0.706 ZopeProfiler.py:190(_getStatistics)
        6    0.000    0.000    4.900    0.817 copy.py:292(_deepcopy_inst)
   3848/6    0.480    0.000    4.900    0.817 copy.py:266(_deepcopy_dict)
  55412/7    2.470    0.000    4.900    0.700 copy.py:161(deepcopy)
     1017    0.450    0.000    4.650    0.005 ZWikiPage.py:912(pageWithId)
      880    0.210    0.000    4.460    0.005 ClientStorage.py:722(load)
       79    0.000    0.000    4.430    0.056 ZWikiPage.py:976(pageWithFuzzyName)
8795/6194    1.080    0.000    4.170    0.001 copy.py:247(_deepcopy_tuple)
     1341    0.350    0.000    4.070    0.003 Tracker.py:149(issueColour)
      736    0.040    0.000    4.060    0.006 TALES.py:225(evaluateText)
      920    0.070    0.000    3.990    0.004 TALInterpreter.py:265(do_startTag)
      803    0.080    0.000    3.890    0.005 ZRPythonExpr.py:42(__call__)
      813    0.040    0.000    3.830    0.005 ServerStub.py:81(zeoLoad)
      661    0.030    0.000    3.830    0.006 TALInterpreter.py:342(attrAction_tal)
      823    0.050    0.000    3.820    0.005 connection.py:363(call)
     1341    2.260    0.002    3.720    0.003 Tracker.py:161(issueColourFor)
4380/3780    0.390    0.000    3.550    0.001 sre.py:139(sub)
        2    0.000    0.000    3.530    1.765 StandardStorage.py:47(insert)
    33180    1.990    0.000    3.360    0.000 Lazy.py:137(__getitem__)
      823    0.390    0.000    3.200    0.004 connection.py:441(wait)
     1379    0.470    0.000    3.010    0.002 DateTime.py:487(__init__)
     1154    0.080    0.000    2.950    0.003 Utils.py:351(lastEditTime)
       15    0.000    0.000    2.860    0.191 ZWikiPage.py:440(renderMarkedLinksIn)
      202    0.150    0.001    2.850    0.014 ZWikiPage.py:473(renderLink)
    29179    1.400    0.000    2.770    0.000 DocumentClass.py:317(parse)
       97    0.460    0.005    2.680    0.028 ZWikiPage.py:893(defaultPage)
     6608    0.180    0.000    2.550    0.000 sre.py:216(_compile)
       97    0.000    0.000    2.360    0.024 sre_compile.py:459(compile)
        2    0.000    0.000    2.340    1.170 PageTemplate.py:162(_cook)
        2    0.000    0.000    2.330    1.165 HTMLParser.py:107(feed)
        4    0.040    0.010    2.330    0.583 HTMLParser.py:142(goahead)
        2    0.000    0.000    2.330    1.165 HTMLTALParser.py:125(parseString)
      353    0.020    0.000    2.300    0.007 TALInterpreter.py:458(do_setLocal_tal)
      116    0.000    0.000    2.080    0.018 RCompile.py:92(compile_restricted_eval)
      116    0.000    0.000    2.050    0.018 RCompile.py:66(compileAndTuplize)
      116    0.010    0.000    2.040    0.018 RCompile.py:60(compile)
      109    0.010    0.000    1.920    0.018 Utils.py:255(urlForDtmlPageOrMethod)
    13227    0.710    0.000    1.900    0.000 ZWikiPage.py:807(<lambda>)
       26    0.010    0.000    1.750    0.067 Python expression "here.wikipage_macros(request).macros":1(<expression>)
     1153    0.140    0.000    1.750    0.002 Utils.py:62(summary)
       52    0.000    0.000    1.720    0.033 PageTemplate.py:51(macros)
       52    0.000    0.000    1.720    0.033 PageTemplate.py:121(pt_macros)
        2    0.030    0.015    1.710    0.855 StandardLexicon.py:57(getWordIdList)
      214    0.000    0.000    1.600    0.007 TALGenerator.py:206(compileExpression)
  219/214    0.010    0.000    1.600    0.007 TALES.py:121(compile)
       95    0.010    0.000    1.570    0.017 ZRPythonExpr.py:33(__init__)
     1191    0.440    0.000    1.560    0.001 DateTime.py:902(_parse)
  2492/97    0.510    0.000    1.500    0.015 sre_parse.py:367(_parse)
   210/97    0.110    0.001    1.500    0.015 sre_parse.py:312(_parse_sub)
       97    0.000    0.000    1.500    0.015 sre_parse.py:614(parse)
        6    0.340    0.057    1.420    0.237 pstats.py:240(strip_dirs)
      880    0.370    0.000    1.390    0.002 Connection.py:598(_set_ghost_state)
      241    0.070    0.000    1.350    0.006 HTMLParser.py:249(parse_starttag)
     1153    0.090    0.000    1.240    0.001 Comments.py:58(documentPart)
       18    0.000    0.000    1.230    0.068 UnIndex.py:233(index_object)
     1706    0.590    0.000    1.200    0.001 asyncore.py:94(poll)
       56    0.000    0.000    1.190    0.021 Utils.py:330(preferencesUrl)
     3579    0.220    0.000    1.160    0.000 DateTime.py:383(_tzoffset)
      241    0.060    0.000    1.110    0.005 TALGenerator.py:477(emitStartElement)
      212    0.040    0.000    1.080    0.005 HTMLTALParser.py:140(handle_starttag)
      882    0.040    0.000    1.080    0.001 sre.py:129(match)
       24    0.000    0.000    1.060    0.044 Bindings.py:308(__render_with_namespace__)
    14379    0.610    0.000    1.040    0.000 CatalogBrains.py:28(getPath)
     8928    0.650    0.000    1.000    0.000 Connection.py:180(_persistent_load)
      241    0.050    0.000    0.970    0.004 TALGenerator.py:710(emitEndElement)
    64368    0.970    0.000    0.970    0.000 Tracker.py:172(<lambda>)
    10623    0.270    0.000    0.930    0.000 pstats.py:450(func_strip_path)
     1186    0.170    0.000    0.920    0.001 DateTime.py:874(_calcTimezoneName)
     5099    0.250    0.000    0.880    0.000 __init__.py:126(LOG)
    21510    0.880    0.000    0.880    0.000 BTreeFolder2.py:192(_getOb)
     1587    0.210    0.000    0.870    0.001 Utils.py:237(page_url)
        6    0.000    0.000    0.870    0.145 sre.py:177(compile)
        2    0.000    0.000    0.870    0.435 KeywordIndex.py:48(_index_object)
     4236    0.130    0.000    0.870    0.000 connection.py:179(log)
      116    0.010    0.000    0.860    0.007 RCompile.py:51(_get_tree)
       97    0.010    0.000    0.860    0.009 sre_compile.py:444(_code)
      211    0.030    0.000    0.850    0.004 HTMLParser.py:331(parse_endtag)
       39    0.010    0.000    0.840    0.022 Python expression "here.preferencesUrl()+prefs":1(<expression>)
     3577    0.190    0.000    0.840    0.000 DateTime.py:97(info)
     1152    0.190    0.000    0.830    0.001 CatalogBrains.py:39(getObject)
        2    0.000    0.000    0.830    0.415 KeywordIndex.py:91(_get_object_keywords)
        1    0.000    0.000    0.830    0.830 ZWikiPage.py:602(canonicalLinks)
        6    0.450    0.075    0.820    0.137 pstats.py:203(sort_stats)
      211    0.010    0.000    0.810    0.004 HTMLTALParser.py:173(handle_endtag)
     1472    0.040    0.000    0.780    0.001 HTTPRequest.py:1346(__getattr__)
     1168    0.740    0.001    0.780    0.001 sre.py:153(split)
    67/64    0.140    0.002    0.760    0.012 DT_In.py:618(renderwob)
    23/11    0.020    0.001    0.740    0.067 DT_With.py:58(render)
      469    0.290    0.001    0.690    0.001 HTTPRequest.py:1231(keys)
      860    0.150    0.000    0.690    0.001 connection.py:348(send_call)
     1191    0.180    0.000    0.690    0.001 DateTime.py:317(_calcIndependentSecondEtc)
     2312    0.100    0.000    0.670    0.000 Editing.py:726(text)
    10626    0.370    0.000    0.660    0.000 posixpath.py:110(basename)
     3577    0.590    0.000    0.650    0.000 DateTime.py:74(index)
       63    0.000    0.000    0.640    0.010 TALGenerator.py:297(emitCondition)
      434    0.020    0.000    0.640    0.001 ZWikiPage.py:954(pageWithName)
       38    0.010    0.000    0.640    0.017 Utils.py:251(defaultPageUrl)
  348/232    0.030    0.000    0.630    0.003 visitor.py:101(walk)
     5099    0.250    0.000    0.630    0.000 EventLogger.py:42(log)
  348/232    0.020    0.000    0.620    0.003 visitor.py:59(preorder)
 2778/232    0.150    0.000    0.620    0.003 visitor.py:42(dispatch)
    32886    0.620    0.000    0.620    0.000 copy.py:276(_keep_alive)
      116    0.000    0.000    0.600    0.005 pycodegen.py:243(getCode)
      116    0.000    0.000    0.600    0.005 pyassem.py:365(getCode)
  2656/97    0.350    0.000    0.580    0.006 sre_compile.py:24(_compile)
     2399    0.120    0.000    0.570    0.000 Editing.py:721(read)
      116    0.000    0.000    0.570    0.005 pycodegen.py:1160(__init__)
  7366/50    0.540    0.000    0.540    0.011 Outline.py:308(flatten)
       50    0.000    0.000    0.540    0.011 Outline.py:39(flat)
    13227    0.480    0.000    0.540    0.000 Catalog.py:133(__getitem__)
       44    0.000    0.000    0.540    0.012 TALInterpreter.py:560(do_insertStructure_tal)
     9280    0.420    0.000    0.530    0.000 PageTypes.py:396(doc_sgml)
     1268    0.310    0.000    0.520    0.000 Traversable.py:110(unrestrictedTraverse)
       51    0.000    0.000    0.520    0.010 Eval.py:53(prepRestrictedCode)
     1850    0.130    0.000    0.510    0.000 Utils.py:244(wiki_url)
     6565    0.310    0.000    0.510    0.000 HTTPRequest.py:1211(__getitem__)
     8426    0.310    0.000    0.470    0.000 sre_parse.py:206(get)
      116    0.000    0.000    0.470    0.004 pycodegen.py:354(visitExpression)
       45    0.100    0.002    0.460    0.010 BaseRequest.py:174(traverse)
        6    0.020    0.003    0.450    0.075 pstats.py:333(print_stats)
    14379    0.430    0.000    0.430    0.000 ZCatalog.py:549(getpath)
    28200    0.430    0.000    0.430    0.000 DocumentClass.py:244(setColorizableTexts)
    28200    0.430    0.000    0.430    0.000 DocumentClass.py:243(getColorizableTexts)
      372    0.050    0.000    0.420    0.001 ZWikiPage.py:968(pageWithNameOrId)
      858    0.040    0.000    0.420    0.000 asyncore.py:75(write)
      116    0.000    0.000    0.410    0.004 RCompile.py:48(parse)
      116    0.010    0.000    0.410    0.004 RCompile.py:27(niceParse)
     2516    0.300    0.000    0.410    0.000 threading.py:93(acquire)
       25    0.000    0.000    0.410    0.016 Utils.py:278(contentsUrl)
       17    0.000    0.000    0.410    0.024 Utils.py:305(changesUrl)
     3905    0.080    0.000    0.400    0.000 HTTPRequest.py:1226(has_key)
      991    0.270    0.000    0.400    0.000 pstats.py:404(print_line)
      116    0.010    0.000    0.400    0.003 transformer.py:48(parse)
       12    0.000    0.000    0.400    0.033 TALGenerator.py:266(emitDefines)
     6184    0.390    0.000    0.390    0.000 BTreeFolder2.py:472(__getattr__)
     1190    0.070    0.000    0.390    0.000 Rating.py:85(rating)
      116    0.020    0.000    0.380    0.003 transformer.py:122(parseexpr)
     5099    0.100    0.000    0.380    0.000 __init__.py:947(log)
      858    0.080    0.000    0.380    0.000 asyncore.py:392(handle_write_event)
      116    0.000    0.000    0.360    0.003 transformer.py:109(transform)

         392 function calls (365 primitive calls) in 57.490 CPU seconds

   Ordered by: cumulative time

   ncalls  tottime  percall  cumtime  percall filename:lineno(function)
        4    7.500    1.875   18.910    4.727 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(Request)
       13   13.070    1.005   14.160    1.089 wikipage:0(__call__)
        3    7.070    2.357   11.380    3.793 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(render)
        3    0.000    0.000   11.380    3.793 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(__call__)
        1    0.010    0.010    8.610    8.610 favicon.ico:0(Request)
      4/2    0.570    0.143    8.600    4.300 standard_error_message:0(__call__)
        5    8.140    1.628    8.140    1.628 showLow:0(Request)
        2    8.030    4.015    8.030    4.015 APE:0(pages)
        2    0.020    0.010    5.250    2.625 FrontPage:0(Request)
        2    0.000    0.000    5.230    2.615 FrontPage:0(render)
      6/2    0.610    0.102    5.230    2.615 FrontPage:0(__call__)
        1    0.040    0.040    5.000    5.000 ZopeWiki:0(Request)
      3/1    0.020    0.007    4.960    4.960 ZopeWiki:0(__call__)
        1    0.000    0.000    4.960    4.960 ZopeWiki:0(render)
        1    0.000    0.000    4.110    4.110 ZopeWiki:0(evaluatePreRenderedAsDtml)
        1    4.080    4.080    4.080    4.080 ZopeWiki:0(pages)
        3    0.000    0.000    4.070    1.357 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(addSkinTo)
        2    0.200    0.100    3.950    1.975 ZopeProfiler:0(Request)
        1    0.000    0.000    3.750    3.750 ZopeProfiler:0(__call__)
        1    0.010    0.010    3.750    3.750 ZopeProfiler:0(render)
        1    0.000    0.000    3.710    3.710 ZopeProfiler:0(addSkinTo)
        2    0.010    0.005    3.580    1.790 FrontPage:0(evaluatePreRenderedAsDtml)
        4    2.960    0.740    2.960    0.740 FrontPage:0(pages)
        1    0.010    0.010    2.510    2.510 JoyfulSystems:0(Request)
        1    0.010    0.010    2.500    2.500 JoyfulSystems:0(addSkinTo)
      3/1    0.000    0.000    2.500    2.500 JoyfulSystems:0(__call__)
        1    0.000    0.000    2.500    2.500 JoyfulSystems:0(render)
        1    0.020    0.020    1.560    1.560 ConfiguringZope27:0(Request)
        1    0.010    0.010    1.540    1.540 ConfiguringZope27:0(__call__)
        1    0.000    0.000    1.530    1.530 ConfiguringZope27:0(render)
        2    1.110    0.555    1.110    0.555 FrontPage:0(renderMarkedLinksIn)
        1    1.000    1.000    1.000    1.000 ConfiguringZope27:0(renderMarkedLinksIn)
        1    0.020    0.020    0.720    0.720 BoaConstructor:0(Request)
        1    0.010    0.010    0.700    0.700 BoaConstructor:0(__call__)
        1    0.000    0.000    0.690    0.690 BoaConstructor:0(render)
        1    0.010    0.010    0.680    0.680 BoaConstructor:0(addSkinTo)
        1    0.660    0.660    0.660    0.660 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(pages)
        1    0.010    0.010    0.650    0.650 ZopeDosAndDonts:0(Request)
        1    0.000    0.000    0.650    0.650 ConfiguringZope:0(__call__)
        1    0.000    0.000    0.650    0.650 ConfiguringZope:0(Request)
        1    0.000    0.000    0.650    0.650 ConfiguringZope:0(render)
        1    0.000    0.000    0.650    0.650 ZopeWiki:0(addSkinTo)
        1    0.000    0.000    0.640    0.640 ZopeDosAndDonts:0(__call__)
        1    0.000    0.000    0.640    0.640 ZopeDosAndDonts:0(render)
        1    0.000    0.000    0.610    0.610 ConfiguringZope:0(addSkinTo)
        1    0.000    0.000    0.600    0.600 HowTos:0(__call__)
        1    0.000    0.000    0.600    0.600 HowTos:0(Request)
        1    0.010    0.010    0.600    0.600 HowTos:0(render)
        1    0.000    0.000    0.570    0.570 HowTos:0(addSkinTo)
        1    0.000    0.000    0.520    0.520 ConfiguringZope27:0(addSkinTo)
        2    0.000    0.000    0.510    0.255 FrontPage:0(addSkinTo)
        1    0.380    0.380    0.380    0.380 showHigh:0(Request)
        1    0.000    0.000    0.360    0.360 ZopeDosAndDonts:0(addSkinTo)
       12    0.260    0.022    0.260    0.022 ratingform:0(__call__)
        1    0.250    0.250    0.250    0.250 ZopeDosAndDonts:0(renderMarkedLinksIn)
        3    0.230    0.077    0.230    0.077 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(renderMarkedLinksIn)
        1    0.180    0.180    0.180    0.180 ZopeWiki:0(renderMarkedLinksIn)
        5    0.100    0.020    0.170    0.034 :0(Request)
        8    0.110    0.014    0.170    0.021 stylesheet:0(Request)
        3    0.080    0.027    0.080    0.027 memusage:0(__call__)
     10/5    0.070    0.007    0.070    0.014 index_html:0(__call__)
        2    0.000    0.000    0.070    0.035 FrontPage:0(ratingform)
        2    0.000    0.000    0.060    0.030 RandomTip:0(render)
      6/2    0.010    0.002    0.060    0.030 RandomTip:0(__call__)
        7    0.010    0.001    0.050    0.007 APE:0(stylesheet)
        1    0.040    0.040    0.050    0.050 mailin:0(Request)
        3    0.000    0.000    0.050    0.017 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(ratingform)
     14/7    0.040    0.003    0.040    0.006 stylesheet:0(__call__)
        1    0.010    0.010    0.040    0.040 ConfiguringZope27:0(ratingform)
        2    0.040    0.020    0.040    0.020 RandomTip:0(renderMarkedLinksIn)
        1    0.040    0.040    0.040    0.040 ConfiguringZope:0(renderMidsectionIn)
        1    0.020    0.020    0.030    0.030 linkToAllCataloged:0(Request)
        2    0.030    0.015    0.030    0.015 FrontPage:0(renderMidsectionIn)
        1    0.030    0.030    0.030    0.030 ltab:0(Request)
        1    0.030    0.030    0.030    0.030 ZopeDosAndDonts:0(renderMidsectionIn)
        1    0.030    0.030    0.030    0.030 js2.jpg:0(Request)
        1    0.010    0.010    0.030    0.030 ZopeWiki:0(ratingform)
       44    0.030    0.001    0.030    0.001 vurt:0(__call__)
        1    0.000    0.000    0.030    0.030 ZopeProfiler:0(ratingform)
        1    0.030    0.030    0.030    0.030 pc3.gif:0(Request)
        1    0.020    0.020    0.020    0.020 HowTos:0(renderMarkedLinksIn)
        1    0.020    0.020    0.020    0.020 ZopeProfiler:0(renderMarkedLinksIn)
        1    0.020    0.020    0.020    0.020 rtab:0(Request)
        1    0.020    0.020    0.020    0.020 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(markLinksIn)
        1    0.020    0.020    0.020    0.020 ZopeWiki:0(renderMidsectionIn)
        1    0.000    0.000    0.020    0.020 HowTos:0(ratingform)
        1    0.000    0.000    0.020    0.020 ConfiguringZope:0(ratingform)
        1    0.000    0.000    0.020    0.020 ZopeDosAndDonts:0(ratingform)
        1    0.020    0.020    0.020    0.020 robots.txt:0(Request)
        1    0.010    0.010    0.010    0.010 ConfiguringZope27:0(renderMidsectionIn)
        1    0.010    0.010    0.010    0.010 mailin:0(__call__)
        2    0.010    0.005    0.010    0.005 RandomTip:0(renderMidsectionIn)
        1    0.010    0.010    0.010    0.010 BoaConstructor:0(renderMarkedLinksIn)
        2    0.000    0.000    0.010    0.005 RandomTip:0(evaluatePreRenderedAsDtml)
        1    0.010    0.010    0.010    0.010 ZopeProfiler:0(renderMidsectionIn)
        1    0.000    0.000    0.010    0.010 BoaConstructor:0(ratingform)
        3    0.010    0.003    0.010    0.003 ZopeWiki:0(getSkinTemplate)
        2    0.010    0.005    0.010    0.005 HowTos:0(wikipage_macros)
        1    0.010    0.010    0.010    0.010 manage_page_style.css:0(Request)
        3    0.010    0.003    0.010    0.003 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(renderMidsectionIn)
        1    0.000    0.000    0.000    0.000 uptime:0(__call__)
        2    0.000    0.000    0.000    0.000 RandomTip:0(applyWikiLinkLineEscapesIn)
        1    0.000    0.000    0.000    0.000 ConfiguringZope27:0(evaluatePreRenderedAsDtml)
        3    0.000    0.000    0.000    0.000 ConfiguringZope27:0(getSkinTemplate)
        1    0.000    0.000    0.000    0.000 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(applyWikiLinkLineEscapesIn)
        1    0.000    0.000    0.000    0.000 AboutJoyfulSystems:0(stylesheet)
        1    0.000    0.000    0.000    0.000 ZopeDosAndDonts:0(evaluatePreRenderedAsDtml)
        3    0.000    0.000    0.000    0.000 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(evaluatePreRenderedAsDtml)
        4    0.000    0.000    0.000    0.000 FrontPage:0(wikipage_macros)
        2    0.000    0.000    0.000    0.000 FrontPage:0(applyWikiLinkLineEscapesIn)
        1    0.000    0.000    0.000    0.000 HowTos:0(renderMidsectionIn)
       44    0.000    0.000    0.000    0.000 Localizer:0(__call__)
        1    0.000    0.000    0.000    0.000 JoyfulSystems:0(renderMidsectionIn)
        2    0.000    0.000    0.000    0.000 ZopeDosAndDonts:0(wikipage_macros)
        3    0.000    0.000    0.000    0.000 ZopeProfiler:0(getSkinTemplate)
        1    0.000    0.000    0.000    0.000 sp:0(Request)
        2    0.000    0.000    0.000    0.000 JoyfulSystems:0(getSkinTemplate)
        6    0.000    0.000    0.000    0.000 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(wikipage_macros)
        1    0.000    0.000    0.000    0.000 BoaConstructor:0(renderMidsectionIn)
        3    0.000    0.000    0.000    0.000 HowTos:0(getSkinTemplate)
        1    0.000    0.000    0.000    0.000 ConfiguringZope:0(renderMarkedLinksIn)
        3    0.000    0.000    0.000    0.000 ZopeDosAndDonts:0(getSkinTemplate)
        2    0.000    0.000    0.000    0.000 JoyfulSystems:0(wikipage_macros)
        2    0.000    0.000    0.000    0.000 ZopeProfiler:0(wikipage_macros)
        1    0.000    0.000    0.000    0.000 JoyfulSystems:0(renderMarkedLinksIn)
        2    0.000    0.000    0.000    0.000 ZopeWiki:0(wikipage_macros)
        2    0.000    0.000    0.000    0.000 ConfiguringZope:0(wikipage_macros)
      2/1    0.000    0.000    0.000    0.000 robots.txt:0(__call__)
        2    0.000    0.000    0.000    0.000 RandomTip:0(addSkinTo)
        2    0.000    0.000    0.000    0.000 BoaConstructor:0(wikipage_macros)
        3    0.000    0.000    0.000    0.000 BoaConstructor:0(getSkinTemplate)
        9    0.000    0.000    0.000    0.000 IssueNo0800LoadsOfBlankLinesInZwiki030ParsedHtml:0(getSkinTemplate)
        6    0.000    0.000    0.000    0.000 FrontPage:0(getSkinTemplate)
        1    0.000    0.000    0.000    0.000 ZopeProfiler:0(evaluatePreRenderedAsDtml)
        1    0.000    0.000    0.000    0.000 JoyfulSystems:0(evaluatePreRenderedAsDtml)
        1    0.000    0.000    0.000    0.000 ConfiguringZope:0(evaluatePreRenderedAsDtml)
        1    0.000    0.000    0.000    0.000 HowTos:0(evaluatePreRenderedAsDtml)
        1    0.000    0.000    0.000    0.000 BoaConstructor:0(evaluatePreRenderedAsDtml)
        3    0.000    0.000    0.000    0.000 ConfiguringZope:0(getSkinTemplate)
        2    0.000    0.000    0.000    0.000 ConfiguringZope27:0(wikipage_macros)


comments:

(property change) closing --simon, Wed, 08 Feb 2006 18:39:56 -0800 reply
Status: open => closed

No more full/simple/minimal mode.. I do think a small unresolved slowdown was introduced, but it's lost in the noise for now.