Profiling partial rendering in Express

1-partial.html

<% # XXX PROFILING %>
<% begin = Date.now() %>

<!-- ... -->

<% # XXX PROFILING %>
<% time = Date.now() - begin %>
<% console.log "partial for thing @#{@thing.id} rendered in #{time} ms" %>

2-view-with-automatic-iteration.html

<% # XXX PROFILING %>
<% begin = Date.now() %>

<!-- ... -->

    <% for category in categories: %>

        <!-- ... -->

        <% # XXX PROFILING %>
        <% beginCategory = Date.now() %>

        <%- @partial 'partials/thing', {collection: things, as: 'thing'} %>

        <% # XXX PROFILING %>
        <% time = Date.now() - beginCategory %>
        <% console.log "#{things.length} thing partials rendered in #{time} ms\n" %>

        <!-- ... -->

    <% end %>

<!-- ... -->

3-output-of-automatic-iteration.txt

partial for thing @8 rendered in 0 ms
partial for thing @5 rendered in 0 ms
partial for thing @16 rendered in 0 ms
partial for thing @17 rendered in 0 ms
partial for thing @7 rendered in 0 ms
5 thing partials rendered in 27 ms

partial for thing @58 rendered in 0 ms
partial for thing @60 rendered in 0 ms
partial for thing @89 rendered in 0 ms
partial for thing @90 rendered in 0 ms
partial for thing @87 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @77 rendered in 0 ms
partial for thing @25 rendered in 0 ms
partial for thing @264 rendered in 0 ms
partial for thing @110 rendered in 0 ms
partial for thing @177 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @464 rendered in 0 ms
partial for thing @1441 rendered in 0 ms
partial for thing @1403 rendered in 0 ms
partial for thing @358 rendered in 0 ms
partial for thing @509 rendered in 1 ms
5 thing partials rendered in 26 ms

partial for thing @28 rendered in 1 ms
partial for thing @126 rendered in 1 ms
partial for thing @251 rendered in 0 ms
partial for thing @1524 rendered in 0 ms
partial for thing @285 rendered in 0 ms
5 thing partials rendered in 25 ms

partial for thing @1522 rendered in 0 ms
partial for thing @183 rendered in 0 ms
partial for thing @1518 rendered in 0 ms
partial for thing @193 rendered in 0 ms
partial for thing @492 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @351 rendered in 0 ms
partial for thing @302 rendered in 0 ms
partial for thing @479 rendered in 0 ms
3 thing partials rendered in 15 ms

partial for thing @305 rendered in 0 ms
partial for thing @486 rendered in 0 ms
partial for thing @453 rendered in 0 ms
partial for thing @490 rendered in 0 ms
partial for thing @56 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @37 rendered in 1 ms
partial for thing @33 rendered in 1 ms
partial for thing @34 rendered in 0 ms
partial for thing @67 rendered in 0 ms
partial for thing @1772 rendered in 0 ms
5 thing partials rendered in 25 ms

partial for thing @1568 rendered in 1 ms
partial for thing @1459 rendered in 0 ms
partial for thing @1455 rendered in 1 ms
partial for thing @1992 rendered in 1 ms
partial for thing @595 rendered in 1 ms
5 thing partials rendered in 27 ms

partial for thing @31 rendered in 1 ms
partial for thing @32 rendered in 0 ms
partial for thing @210 rendered in 1 ms
partial for thing @30 rendered in 0 ms
partial for thing @409 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @1614 rendered in 0 ms
partial for thing @360 rendered in 0 ms
partial for thing @1710 rendered in 0 ms
partial for thing @364 rendered in 0 ms
partial for thing @1562 rendered in 1 ms
5 thing partials rendered in 27 ms

partial for thing @131 rendered in 0 ms
partial for thing @62 rendered in 0 ms
partial for thing @1644 rendered in 1 ms
partial for thing @1638 rendered in 0 ms
partial for thing @1468 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @267 rendered in 0 ms
partial for thing @268 rendered in 1 ms
partial for thing @363 rendered in 0 ms
3 thing partials rendered in 16 ms

partial for thing @29 rendered in 0 ms
partial for thing @128 rendered in 0 ms
partial for thing @516 rendered in 0 ms
partial for thing @49 rendered in 0 ms
partial for thing @42 rendered in 0 ms
5 thing partials rendered in 27 ms

partial for thing @1445 rendered in 0 ms
partial for thing @1650 rendered in 0 ms
partial for thing @1753 rendered in 0 ms
partial for thing @1571 rendered in 0 ms
partial for thing @2118 rendered in 0 ms
5 thing partials rendered in 26 ms

total rendering time for view: 398 ms

4-view-with-manual-iteration.html

<% # XXX PROFILING %>
<% begin = Date.now() %>

<!-- ... -->

    <% for category in categories: %>

        <!-- ... -->

        <% # XXX PROFILING %>
        <% beginCategory = Date.now() %>

        <% for thing in things: %>
            <%- @partial 'partials/thing', {thing} %>
        <% end %>

        <% # XXX PROFILING %>
        <% time = Date.now() - beginCategory %>
        <% console.log "#{things.length} thing partials rendered in #{time} ms\n" %>

        <!-- ... -->

    <% end %>

<!-- ... -->

5-output-of-manual-iteration

partial for thing @8 rendered in 0 ms
partial for thing @5 rendered in 0 ms
partial for thing @16 rendered in 0 ms
partial for thing @17 rendered in 0 ms
partial for thing @7 rendered in 0 ms
5 thing partials rendered in 28 ms

partial for thing @58 rendered in 0 ms
partial for thing @60 rendered in 0 ms
partial for thing @89 rendered in 0 ms
partial for thing @90 rendered in 0 ms
partial for thing @87 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @77 rendered in 0 ms
partial for thing @25 rendered in 0 ms
partial for thing @264 rendered in 0 ms
partial for thing @110 rendered in 0 ms
partial for thing @177 rendered in 1 ms
5 thing partials rendered in 26 ms

partial for thing @464 rendered in 0 ms
partial for thing @1441 rendered in 0 ms
partial for thing @1403 rendered in 1 ms
partial for thing @358 rendered in 1 ms
partial for thing @509 rendered in 0 ms
5 thing partials rendered in 25 ms

partial for thing @28 rendered in 0 ms
partial for thing @126 rendered in 1 ms
partial for thing @251 rendered in 0 ms
partial for thing @1524 rendered in 1 ms
partial for thing @285 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @1522 rendered in 0 ms
partial for thing @183 rendered in 0 ms
partial for thing @1518 rendered in 0 ms
partial for thing @193 rendered in 0 ms
partial for thing @492 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @351 rendered in 0 ms
partial for thing @302 rendered in 1 ms
partial for thing @479 rendered in 0 ms
3 thing partials rendered in 15 ms

partial for thing @305 rendered in 0 ms
partial for thing @486 rendered in 0 ms
partial for thing @453 rendered in 0 ms
partial for thing @490 rendered in 1 ms
partial for thing @56 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @37 rendered in 1 ms
partial for thing @33 rendered in 1 ms
partial for thing @34 rendered in 1 ms
partial for thing @67 rendered in 0 ms
partial for thing @1772 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @1568 rendered in 0 ms
partial for thing @1459 rendered in 0 ms
partial for thing @1455 rendered in 0 ms
partial for thing @1992 rendered in 0 ms
partial for thing @595 rendered in 0 ms
5 thing partials rendered in 28 ms

partial for thing @31 rendered in 0 ms
partial for thing @32 rendered in 1 ms
partial for thing @210 rendered in 0 ms
partial for thing @30 rendered in 1 ms
partial for thing @409 rendered in 1 ms
5 thing partials rendered in 27 ms

partial for thing @1614 rendered in 0 ms
partial for thing @360 rendered in 0 ms
partial for thing @1710 rendered in 0 ms
partial for thing @364 rendered in 1 ms
partial for thing @1562 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @131 rendered in 0 ms
partial for thing @62 rendered in 0 ms
partial for thing @1644 rendered in 0 ms
partial for thing @1638 rendered in 0 ms
partial for thing @1468 rendered in 0 ms
5 thing partials rendered in 26 ms

partial for thing @267 rendered in 0 ms
partial for thing @268 rendered in 0 ms
partial for thing @363 rendered in 0 ms
3 thing partials rendered in 16 ms

partial for thing @29 rendered in 0 ms
partial for thing @128 rendered in 0 ms
partial for thing @516 rendered in 0 ms
partial for thing @49 rendered in 0 ms
partial for thing @42 rendered in 1 ms
5 thing partials rendered in 26 ms

partial for thing @1445 rendered in 0 ms
partial for thing @1650 rendered in 0 ms
partial for thing @1753 rendered in 0 ms
partial for thing @1571 rendered in 0 ms
partial for thing @2118 rendered in 0 ms
5 thing partials rendered in 25 ms

total rendering time for view: 399 ms

6-output-of-both-in-production.txt

partial for thing @8 rendered in 0 ms
partial for thing @5 rendered in 0 ms
partial for thing @16 rendered in 1 ms
partial for thing @17 rendered in 0 ms
partial for thing @7 rendered in 0 ms
5 thing partials rendered in 1 ms

partial for thing @58 rendered in 0 ms
partial for thing @60 rendered in 0 ms
partial for thing @89 rendered in 0 ms
partial for thing @90 rendered in 0 ms
partial for thing @87 rendered in 0 ms
5 thing partials rendered in 0 ms

partial for thing @77 rendered in 1 ms
partial for thing @25 rendered in 0 ms
partial for thing @264 rendered in 0 ms
partial for thing @110 rendered in 0 ms
partial for thing @177 rendered in 0 ms
5 thing partials rendered in 1 ms

partial for thing @464 rendered in 0 ms
partial for thing @1441 rendered in 0 ms
partial for thing @1403 rendered in 0 ms
partial for thing @358 rendered in 1 ms
partial for thing @509 rendered in 0 ms
5 thing partials rendered in 1 ms

partial for thing @28 rendered in 0 ms
partial for thing @126 rendered in 0 ms
partial for thing @251 rendered in 0 ms
partial for thing @1524 rendered in 0 ms
partial for thing @285 rendered in 0 ms
5 thing partials rendered in 0 ms

partial for thing @1522 rendered in 0 ms
partial for thing @183 rendered in 1 ms
partial for thing @1518 rendered in 0 ms
partial for thing @193 rendered in 0 ms
partial for thing @492 rendered in 0 ms
5 thing partials rendered in 1 ms

partial for thing @351 rendered in 0 ms
partial for thing @302 rendered in 0 ms
partial for thing @479 rendered in 0 ms
3 thing partials rendered in 0 ms

partial for thing @305 rendered in 0 ms
partial for thing @486 rendered in 1 ms
partial for thing @453 rendered in 0 ms
partial for thing @490 rendered in 0 ms
partial for thing @56 rendered in 0 ms
5 thing partials rendered in 1 ms

partial for thing @37 rendered in 0 ms
partial for thing @33 rendered in 0 ms
partial for thing @34 rendered in 0 ms
partial for thing @67 rendered in 0 ms
partial for thing @1772 rendered in 1 ms
5 thing partials rendered in 1 ms

partial for thing @1568 rendered in 0 ms
partial for thing @1459 rendered in 0 ms
partial for thing @1455 rendered in 0 ms
partial for thing @1992 rendered in 0 ms
partial for thing @595 rendered in 0 ms
5 thing partials rendered in 0 ms

partial for thing @31 rendered in 0 ms
partial for thing @32 rendered in 0 ms
partial for thing @210 rendered in 0 ms
partial for thing @30 rendered in 0 ms
partial for thing @409 rendered in 0 ms
5 thing partials rendered in 1 ms

partial for thing @1614 rendered in 0 ms
partial for thing @360 rendered in 0 ms
partial for thing @1710 rendered in 0 ms
partial for thing @364 rendered in 0 ms
partial for thing @1562 rendered in 0 ms
5 thing partials rendered in 1 ms

partial for thing @131 rendered in 0 ms
partial for thing @62 rendered in 0 ms
partial for thing @1644 rendered in 0 ms
partial for thing @1638 rendered in 0 ms
partial for thing @1468 rendered in 0 ms
5 thing partials rendered in 0 ms

partial for thing @267 rendered in 0 ms
partial for thing @268 rendered in 0 ms
partial for thing @363 rendered in 1 ms
3 thing partials rendered in 1 ms

partial for thing @29 rendered in 0 ms
partial for thing @128 rendered in 0 ms
partial for thing @516 rendered in 0 ms
partial for thing @49 rendered in 0 ms
partial for thing @42 rendered in 0 ms
5 thing partials rendered in 0 ms

partial for thing @1445 rendered in 0 ms
partial for thing @1650 rendered in 0 ms
partial for thing @1753 rendered in 1 ms
partial for thing @1571 rendered in 0 ms
partial for thing @2118 rendered in 0 ms
5 thing partials rendered in 1 ms

total rendering time for view: 10 ms