$ bzr --lsprof rio-test --limit 100 | head -n30 processed 100 inventories extracted in 1.36s (13.6ms avg) Times read (s) read avg (ms) write (s) write avg (ms) xml: 2.70 27.0 11.98 119.8 rio: 10.37 103.7 5.56 55.6 ratio: 3.8471 0.4642 1.0860 CallCount Recursive Total(ms) Inline(ms) module:lineno(function) 29564 29464 9419.0500 4282.1730 elementtree.ElementTree:662(_write) +29464 0 9206.9930 4080.8820 +elementtree.ElementTree:662(_write) +169977 0 3192.1820 2025.3280 +bzrlib.xml_serializer:92(_escape_attrib) +199641 0 1412.0370 1412.0370 +elementtree.ElementTree:746(_encode) +29564 0 532.6580 343.6660 +bzrlib.xml_serializer:123(_escape_cdata) 100 0 6798.0940 4256.0010 bzrlib.rio:213(read_stanza) +199441 0 1343.4640 1343.4640 +bzrlib.rio:105(add) +199441 0 1197.7450 1197.7450 +encodings.utf_8:15(decode) +100 0 0.8840 0.8840 +bzrlib.rio:95(__init__) 399182 0 2767.8830 2767.8830 elementtree.ElementTree:746(_encode) 199441 0 2167.2930 2167.2930 bzrlib.plugins.rio_inventory.rio_serializer:18(add) 169977 0 3192.1820 2025.3280 bzrlib.xml_serializer:92(_escape_attrib) +169977 0 1166.8540 1166.8540 +elementtree.ElementTree:746(_encode) 176182 117054 2479.6980 1728.0320 bzrlib.inventory:875(iter_entries) +117054 60554 1435.2430 1052.6180 +bzrlib.inventory:875(iter_entries) +111090 0 751.6660 751.6660 +posixpath:56(join) 59288 0 3146.1810 1679.0090 bzrlib.xml5:137(_unpack_entry) +53288 0 1130.7470 651.0690 +bzrlib.inventory:644(__init__) +59288 0 219.6110 219.6110 +bzrlib.inventory:331(versionable_kind) +6000 0 116.8140 64.9890 +bzrlib.inventory:542(__init__) 29464 0 3919.6050 1648.7840 bzrlib.plugins.rio_inventory.rio_serializer:80(_pack_entry) +199241 0 2163.1010 2163.1010 +bzrlib.plugins.rio_inventory.rio_serializer:18(add) +29464 0 107.7200 107.7200 +bzrlib.inventory:331(versionable_kind)