About Arbortext Styler > Debugging Stylesheets > Performance Profiling for Arbortext Styler or FOSI Stylesheets > Sample Output of set debug Command
  
Sample Output of set debug Command
The data shown below is the output for the command set debug=10.25-10.27,=filename, (i.e. a request to provide performance information related to context/eic matching, condition/att matching and generated text processing) run when previewing the Arbortext Article sample document for print. This sample document is based on the XML DocBook V4.0 doctype. The information in each line is arranged in the following columns:
• Column 1: the element being assessed
• Column 2: the number of occurrences of that element in the document
• Column 3: the total time taken to process all occurrences of the element in the document
• Column 4: the average time taken to process a single occurrence of the element (i.e. Column 3 divided by Column 2)
• Column 5: the type of information requested displayed in the request
Note that the times shown are given in milliseconds - a time of 1000.00 msecs equals one second.
============= Start timing data for operation: computing generated text ===============
---------------------- (context/eic matching) ----------------------
Element _font:
108 events
2.182 total msecs
0.020 average msecs
(context/eic matching)
Element _gtlink:
15 events
0.288 total msecs
0.019 average msecs
(context/eic matching)
Element _sfe:BeforeOrAfterText:
108 events
2.410 total msecs
0.022 average msecs
(context/eic matching)
Element _tiv:
30 events
0.547 total msecs
0.018 average msecs
(context/eic matching)
Element abstract:
5 events
0.108 total msecs
0.022 average msecs
(context/eic matching)
Element article:
131 events
2.574 total msecs
0.020 average msecs
(context/eic matching)
Element articleinfo:
1 events
0.020 total msecs
0.020 average msecs
(context/eic matching)
Element atidmd:Bookmark:
30 events
0.664 total msecs
0.022 average msecs
(context/eic matching)
Element atidmd:DocumentMetaData:
2 events
0.047 total msecs
0.023 average msecs
(context/eic matching)
Element atidmd:Outline:
32 events
0.698 total msecs
0.022 average msecs
(context/eic matching)
Element atidmd:Title:
42 events
0.929 total msecs
0.022 average msecs
(context/eic matching)
Element authorgroup:
1 events
0.021 total msecs
0.021 average msecs
(context/eic matching)
Element bmrk__bot_of_div*:
14 events
0.312 total msecs
0.022 average msecs
(context/eic matching)
Element bmrk__top_of_div*:
14 events
0.318 total msecs
0.023 average msecs
(context/eic matching)
Element bmrk__within_div_title*:
14 events
0.310 total msecs
0.022 average msecs
(context/eic matching)
Element copyright:
1 events
0.020 total msecs
0.020 average msecs
(context/eic matching)
Element corpauthor:
1 events
0.021 total msecs
0.021 average msecs
(context/eic matching)
Element emphasis:
21 events
0.474 total msecs
0.023 average msecs
(context/eic matching)
Element figure:
5 events
0.098 total msecs
0.020 average msecs
(context/eic matching)
Element holder:
1 events
0.019 total msecs
0.019 average msecs
(context/eic matching)
Element imagedata:
5 events
0.104 total msecs
0.021 average msecs
(context/eic matching)
Element imageobject:
5 events
0.107 total msecs
0.021 average msecs
(context/eic matching)
Element itemizedlist:
109 events
4.052 total msecs
0.037 average msecs
(context/eic matching)
Element legalnotice:
1 events
0.020 total msecs
0.020 average msecs
(context/eic matching)
Element listitem:
129 events
6.534 total msecs
0.051 average msecs
(context/eic matching)
Element mediaobject:
5 events
0.109 total msecs
0.022 average msecs
(context/eic matching)
Element objectinfo:
5 events
0.107 total msecs
0.021 average msecs
(context/eic matching)
Element orderedlist:
39 events
1.442 total msecs
0.037 average msecs
(context/eic matching)
Element para:
98 events
7.232 total msecs
0.074 average msecs
(context/eic matching)
Element releaseinfo:
1 events
0.021 total msecs
0.021 average msecs
(context/eic matching)
Element section:
225 events
6.975 total msecs
0.031 average msecs
(context/eic matching)
Element subtitle:
1 events
0.021 total msecs
0.021 average msecs
(context/eic matching)
Element title:
104 events
3.995 total msecs
0.038 average msecs
(context/eic matching)
Element year:
1 events
0.019 total msecs
0.019 average msecs
(context/eic matching)
Element #TOTAL#:
1304 events
42.795 total msecs
0.033 average msecs
(context/eic matching)
---------------------- (condition/att matching) ----------------------
Element _font:
108 events
98.476 total msecs
0.912 average msecs
(condition/att matching)
Element _gtlink:
15 events
0.527 total msecs
0.035 average msecs
(condition/att matching)
Element _sfe:BeforeOrAfterText:
54 events
1.065 total msecs
0.020 average msecs
(condition/att matching)
Element abstract:
5 events
0.094 total msecs
0.019 average msecs
(condition/att matching)
Element article:
1 events
0.574 total msecs
0.574 average msecs
(condition/att matching)
Element articleinfo:
1 events
0.018 total msecs
0.018 average msecs
(condition/att matching)
Element atidmd:Bookmark:
15 events
0.286 total msecs
0.019 average msecs
(condition/att matching)
Element atidmd:DocumentMetaData:
1 events
0.019 total msecs
0.019 average msecs
(condition/att matching)
Element atidmd:Outline:
1 events
0.019 total msecs
0.019 average msecs
(condition/att matching)
Element atidmd:Title:
14 events
0.270 total msecs
0.019 average msecs
(condition/att matching)
Element authorgroup:
1 events
0.017 total msecs
0.017 average msecs
(condition/att matching)
Element bmrk__bot_of_div*:
14 events
7.143 total msecs
0.510 average msecs
(condition/att matching)
Element bmrk__top_of_div*:
14 events
6.233 total msecs
0.445 average msecs
(condition/att matching)
Element bmrk__within_div_title*:
14 events
6.319 total msecs
0.451 average msecs
(condition/att matching)
Element copyright:
1 events
0.027 total msecs
0.027 average msecs
(condition/att matching)
Element corpauthor:
1 events
0.057 total msecs
0.057 average msecs
(condition/att matching)
Element emphasis:
21 events
4.362 total msecs
0.208 average msecs
(condition/att matching)
Element figure:
5 events
0.087 total msecs
0.017 average msecs
(condition/att matching)
Element holder:
1 events
0.027 total msecs
0.027 average msecs
(condition/att matching)
Element imagedata:
10 events
0.191 total msecs
0.019 average msecs
(condition/att matching)
Element imageobject:
5 events
0.095 total msecs
0.019 average msecs
(condition/att matching)
Element itemizedlist:
16 events
0.306 total msecs
0.019 average msecs
(condition/att matching)
Element legalnotice:
1 events
0.078 total msecs
0.078 average msecs
(condition/att matching)
Element listitem:
40 events
7.990 total msecs
0.200 average msecs
(condition/att matching)
Element mediaobject:
5 events
0.101 total msecs
0.020 average msecs
(condition/att matching)
Element objectinfo:
5 events
0.094 total msecs
0.019 average msecs
(condition/att matching)
Element orderedlist:
3 events
0.056 total msecs
0.019 average msecs
(condition/att matching)
Element para:
98 events
1.723 total msecs
0.018 average msecs
(condition/att matching)
Element releaseinfo:
1 events
0.018 total msecs
0.018 average msecs
(condition/att matching)
Element section:
14 events
27.997 total msecs
2.000 average msecs
(condition/att matching)
Element subtitle:
1 events
0.047 total msecs
0.047 average msecs
(condition/att matching)
Element title:
104 events
73.016 total msecs
0.702 average msecs
(condition/att matching)
Element year:
1 events
0.017 total msecs
0.017 average msecs
(condition/att matching)
Element #TOTAL#:
591 events
237.353 total msecs
0.402 average msecs
(condition/att matching)
---------------------- (gentext processing) ----------------------
Element /_font:
54 events
1.050 total msecs
0.019 average msecs
(gentext processing)
Element /_gtlink:
15 events
0.322 total msecs
0.021 average msecs
(gentext processing)
Element /_sfe:BeforeOrAfterText:
54 events
2.347 total msecs
0.043 average msecs
(gentext processing)
Element /abstract:
5 events
0.111 total msecs
0.022 average msecs
(gentext processing)
Element /article:
1 events
0.082 total msecs
0.082 average msecs
(gentext processing)
Element /articleinfo:
1 events
0.021 total msecs
0.021 average msecs
(gentext processing)
Element /atidmd:Bookmark:
15 events
0.789 total msecs
0.053 average msecs
(gentext processing)
Element /atidmd:DocumentMetaData:
1 events
0.051 total msecs
0.051 average msecs
(gentext processing)
Element /atidmd:Outline:
1 events
0.051 total msecs
0.051 average msecs
(gentext processing)
Element /atidmd:Title:
14 events
0.739 total msecs
0.053 average msecs
(gentext processing)
Element /authorgroup:
1 events
0.021 total msecs
0.021 average msecs
(gentext processing)
Element /bmrk__bot_of_div*:
14 events
0.347 total msecs
0.025 average msecs
(gentext processing)
Element /bmrk__top_of_div*:
14 events
0.378 total msecs
0.027 average msecs
(gentext processing)
Element /bmrk__within_div_title*:
14 events
0.362 total msecs
0.026 average msecs
(gentext processing)
Element /copyright:
1 events
0.021 total msecs
0.021 average msecs
(gentext processing)
Element /corpauthor:
1 events
0.021 total msecs
0.021 average msecs
(gentext processing)
Element /emphasis:
21 events
0.468 total msecs
0.022 average msecs
(gentext processing)
Element /figure:
5 events
0.102 total msecs
0.020 average msecs
(gentext processing)
Element /holder:
1 events
0.020 total msecs
0.020 average msecs
(gentext processing)
Element /imageobject:
5 events
0.111 total msecs
0.022 average msecs
(gentext processing)
Element /itemizedlist:
16 events
0.358 total msecs
0.022 average msecs
(gentext processing)
Element /legalnotice:
1 events
0.021 total msecs
0.021 average msecs
(gentext processing)
Element /listitem:
40 events
0.894 total msecs
0.022 average msecs
(gentext processing)
Element /mediaobject:
5 events
0.111 total msecs
0.022 average msecs
(gentext processing)
Element /objectinfo:
5 events
0.110 total msecs
0.022 average msecs
(gentext processing)
Element /orderedlist:
3 events
0.067 total msecs
0.022 average msecs
(gentext processing)
Element /para:
98 events
2.013 total msecs
0.021 average msecs
(gentext processing)
Element /releaseinfo:
1 events
0.021 total msecs
0.021 average msecs
(gentext processing)
Element /section:
14 events
2.017 total msecs
0.144 average msecs
(gentext processing)
Element /subtitle:
1 events
0.022 total msecs
0.022 average msecs
(gentext processing)
Element /title:
20 events
0.438 total msecs
0.022 average msecs
(gentext processing)
Element /year:
1 events
0.020 total msecs
0.020 average msecs
(gentext processing)
Element _font:
54 events
1.274 total msecs
0.024 average msecs
(gentext processing)
Element _gtlink:
15 events
0.361 total msecs
0.024 average msecs
(gentext processing)
Element _sfe:BeforeOrAfterText:
54 events
2.506 total msecs
0.046 average msecs
(gentext processing)
Element abstract:
5 events
0.140 total msecs
0.028 average msecs
(gentext processing)
Element article:
1 events
1.393 total msecs
1.393 average msecs
(gentext processing)
Element articleinfo:
1 events
0.026 total msecs
0.026 average msecs
(gentext processing)
Element atidmd:Bookmark:
15 events
0.844 total msecs
0.056 average msecs
(gentext processing)
Element atidmd:DocumentMetaData:
1 events
0.056 total msecs
0.056 average msecs
(gentext processing)
Element atidmd:Outline:
1 events
0.054 total msecs
0.054 average msecs
(gentext processing)
Element atidmd:Title:
14 events
0.785 total msecs
0.056 average msecs
(gentext processing)
Element authorgroup:
1 events
0.026 total msecs
0.026 average msecs
(gentext processing)
Element bmrk__bot_of_div*:
14 events
0.999 total msecs
0.071 average msecs
(gentext processing)
Element bmrk__top_of_div*:
14 events
0.782 total msecs
0.056 average msecs
(gentext processing)
Element bmrk__within_div_title*:
14 events
0.618 total msecs
0.044 average msecs
(gentext processing)
Element copyright:
1 events
0.146 total msecs
0.146 average msecs
(gentext processing)
Element corpauthor:
1 events
0.026 total msecs
0.026 average msecs
(gentext processing)
Element emphasis:
21 events
0.566 total msecs
0.027 average msecs
(gentext processing)
Element figure:
5 events
0.132 total msecs
0.026 average msecs
(gentext processing)
Element holder:
1 events
0.136 total msecs
0.136 average msecs
(gentext processing)
Element imagedata:
10 events
0.211 total msecs
0.021 average msecs
(gentext processing)
Element imageobject:
5 events
0.136 total msecs
0.027 average msecs
(gentext processing)
Element itemizedlist:
16 events
0.434 total msecs
0.027 average msecs
(gentext processing)
Element legalnotice:
1 events
0.026 total msecs
0.026 average msecs
(gentext processing)
Element listitem:
40 events
137.023 total msecs
3.426 average msecs
(gentext processing)
Element mediaobject:
5 events
0.134 total msecs
0.027 average msecs
(gentext processing)
Element objectinfo:
5 events
0.135 total msecs
0.027 average msecs
(gentext processing)
Element orderedlist:
3 events
0.080 total msecs
0.027 average msecs
(gentext processing)
Element para:
98 events
2.527 total msecs
0.026 average msecs
(gentext processing)
Element releaseinfo:
1 events
0.026 total msecs
0.026 average msecs
(gentext processing)
Element section:
14 events
2.487 total msecs
0.178 average msecs
(gentext processing)
Element subtitle:
1 events
0.091 total msecs
0.091 average msecs
(gentext processing)
Element title:
20 events
63.836 total msecs
3.192 average msecs
(gentext processing)
Element year:
1 events
0.025 total msecs
0.025 average msecs
(gentext processing)
Element #TOTAL#:
896 events
231.544 total msecs
0.258 average msecs
(gentext processing)
============== End timing data for operation: computing generated text ================
============= Start timing data for operation: header/footer processing ===============
---------------------- (context/eic matching) ----------------------
Element _sfe:Gentext:
16 events
0.374 total msecs
0.023 average msecs
(context/eic matching)
Element _sfe:HeaderOrFooter:
16 events
0.353 total msecs
0.022 average msecs
(context/eic matching)
Element colspec:
48 events
0.929 total msecs
0.019 average msecs
(context/eic matching)
Element entry:
48 events
5.698 total msecs
0.119 average msecs
(context/eic matching)
Element informaltable:
16 events
1.845 total msecs
0.115 average msecs
(context/eic matching)
Element row:
16 events
0.315 total msecs
0.020 average msecs
(context/eic matching)
Element tbody:
16 events
0.309 total msecs
0.019 average msecs
(context/eic matching)
Element tgroup:
16 events
0.311 total msecs
0.019 average msecs
(context/eic matching)
Element #TOTAL#:
192 events
10.134 total msecs
0.053 average msecs
(context/eic matching)
---------------------- (condition/att matching) ----------------------
Element _sfe:Gentext:
16 events
0.322 total msecs
0.020 average msecs
(condition/att matching)
Element _sfe:HeaderOrFooter:
16 events
0.313 total msecs
0.020 average msecs
(condition/att matching)
Element colspec:
48 events
0.820 total msecs
0.017 average msecs
(condition/att matching)
Element entry:
48 events
0.821 total msecs
0.017 average msecs
(condition/att matching)
Element informaltable:
16 events
0.311 total msecs
0.019 average msecs
(condition/att matching)
Element row:
16 events
0.274 total msecs
0.017 average msecs
(condition/att matching)
Element tbody:
16 events
0.279 total msecs
0.017 average msecs
(condition/att matching)
Element tgroup:
16 events
0.283 total msecs
0.018 average msecs
(condition/att matching)
Element #TOTAL#:
192 events
3.423 total msecs
0.018 average msecs
(condition/att matching)
---------------------- (gentext processing) ----------------------
Element /_sfe:Gentext:
16 events
0.929 total msecs
0.058 average msecs
(gentext processing)
Element /_sfe:HeaderOrFooter:
16 events
1.005 total msecs
0.063 average msecs
(gentext processing)
Element /entry:
48 events
0.985 total msecs
0.021 average msecs
(gentext processing)
Element /informaltable:
16 events
0.355 total msecs
0.022 average msecs
(gentext processing)
Element /row:
16 events
0.326 total msecs
0.020 average msecs
(gentext processing)
Element /tbody:
16 events
0.324 total msecs
0.020 average msecs
(gentext processing)
Element /tgroup:
16 events
0.324 total msecs
0.020 average msecs
(gentext processing)
Element _sfe:Gentext:
16 events
1.011 total msecs
0.063 average msecs
(gentext processing)
Element _sfe:HeaderOrFooter:
16 events
1.004 total msecs
0.063 average msecs
(gentext processing)
Element colspec:
48 events
0.895 total msecs
0.019 average msecs
(gentext processing)
Element entry:
48 events
1.203 total msecs
0.025 average msecs
(gentext processing)
Element informaltable:
16 events
0.444 total msecs
0.028 average msecs
(gentext processing)
Element row:
16 events
0.402 total msecs
0.025 average msecs
(gentext processing)
Element tbody:
16 events
0.403 total msecs
0.025 average msecs
(gentext processing)
Element tgroup:
16 events
0.397 total msecs
0.025 average msecs
(gentext processing)
Element #TOTAL#:
336 events
10.007 total msecs
0.030 average msecs
(gentext processing)