temp
[tatoo.git] / tests / xmark_small.xml.summary
index 95c5c2d..333dd75 100644 (file)
@@ -1,11 +1,11 @@
 Query: A1 : /site/closed_auctions/closed_auction/annotation/description/text/keyword
-STATS: parsing xml document: 192.482948ms
-STATS: parsing XPath query: 0.085115ms
-STATS: compiling XPath query: 0.261068ms
+STATS: parsing xml document: 410.294056ms
+STATS: parsing XPath query: 0.077963ms
+STATS: compiling XPath query: 0.396013ms
 STATS: Query: /child::site/child::closed_auctions/child::closed_auction/child::annotation/child::description/child::text/child::keyword 
 STATS: Automaton: 
-STATS: evaluating query: 108.249903ms
-STATS: serializing results: 3.324032ms
+STATS: evaluating query: 211.765051ms
+STATS: serializing results: 0.488043ms
 STATS: 1 iterations
 STATS: automaton 0, cache2: 170 entries, cache6: 3149 entries
 STATS: cache2: length: 255, used: 85, occupation: 0.333333
@@ -13,13 +13,13 @@ STATS: cache4: length: 3464, used: 315, occupation: 0.090935
 Diff: ok
 -------------------------------------------
 Query: A2 : //closed_auction//keyword
-STATS: parsing xml document: 192.501068ms
-STATS: parsing XPath query: 0.058889ms
-STATS: compiling XPath query: 0.138998ms
+STATS: parsing xml document: 412.861109ms
+STATS: parsing XPath query: 0.049829ms
+STATS: compiling XPath query: 0.177860ms
 STATS: Query: /descendant-or-self::node()/child::closed_auction/descendant-or-self::node()/child::keyword 
 STATS: Automaton: 
-STATS: evaluating query: 129.096985ms
-STATS: serializing results: 6.552219ms
+STATS: evaluating query: 239.673138ms
+STATS: serializing results: 1.279831ms
 STATS: 1 iterations
 STATS: automaton 0, cache2: 170 entries, cache6: 2681 entries
 STATS: cache2: length: 255, used: 85, occupation: 0.333333
@@ -27,13 +27,13 @@ STATS: cache4: length: 2915, used: 234, occupation: 0.080274
 Diff: ok
 -------------------------------------------
 Query: A3 : /site/closed_auctions/closed_auction//keyword
-STATS: parsing xml document: 185.832024ms
-STATS: parsing XPath query: 0.071049ms
-STATS: compiling XPath query: 0.129938ms
+STATS: parsing xml document: 409.093857ms
+STATS: parsing XPath query: 0.059128ms
+STATS: compiling XPath query: 0.230074ms
 STATS: Query: /child::site/child::closed_auctions/child::closed_auction/descendant-or-self::node()/child::keyword 
 STATS: Automaton: 
-STATS: evaluating query: 123.064041ms
-STATS: serializing results: 6.781101ms
+STATS: evaluating query: 232.646942ms
+STATS: serializing results: 1.214027ms
 STATS: 1 iterations
 STATS: automaton 0, cache2: 170 entries, cache6: 3277 entries
 STATS: cache2: length: 255, used: 85, occupation: 0.333333
@@ -41,13 +41,13 @@ STATS: cache4: length: 3555, used: 278, occupation: 0.078200
 Diff: ok
 -------------------------------------------
 Query: A4 : /site/closed_auctions/closed_auction[annotation/description/text/keyword]/date
-STATS: parsing xml document: 199.089050ms
-STATS: parsing XPath query: 0.107050ms
-STATS: compiling XPath query: 0.153065ms
+STATS: parsing xml document: 411.557913ms
+STATS: parsing XPath query: 0.082970ms
+STATS: compiling XPath query: 0.281811ms
 STATS: Query: /child::site/child::closed_auctions/child::closed_auction[ child::annotation/child::description/child::text/child::keyword ]/child::date 
 STATS: Automaton: 
-STATS: evaluating query: 166.728973ms
-STATS: serializing results: 2.009153ms
+STATS: evaluating query: 313.055992ms
+STATS: serializing results: 0.196934ms
 STATS: 2 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 1324 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -55,13 +55,13 @@ STATS: cache4: length: 1501, used: 177, occupation: 0.117921
 Diff: ok
 -------------------------------------------
 Query: A5 : /site/closed_auctions/closed_auction[descendant::keyword]/date
-STATS: parsing xml document: 187.058926ms
-STATS: parsing XPath query: 0.071049ms
-STATS: compiling XPath query: 0.151873ms
+STATS: parsing xml document: 407.375097ms
+STATS: parsing XPath query: 0.066996ms
+STATS: compiling XPath query: 0.221014ms
 STATS: Query: /child::site/child::closed_auctions/child::closed_auction[ descendant::keyword ]/child::date 
 STATS: Automaton: 
-STATS: evaluating query: 167.564154ms
-STATS: serializing results: 2.597094ms
+STATS: evaluating query: 307.749987ms
+STATS: serializing results: 0.308990ms
 STATS: 2 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 1170 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -69,13 +69,13 @@ STATS: cache4: length: 1328, used: 158, occupation: 0.118976
 Diff: ok
 -------------------------------------------
 Query: A6 : /site/people/person[profile/gender and profile/age]/name
-STATS: parsing xml document: 189.209938ms
-STATS: parsing XPath query: 0.105143ms
-STATS: compiling XPath query: 0.147104ms
+STATS: parsing xml document: 408.198118ms
+STATS: parsing XPath query: 0.082970ms
+STATS: compiling XPath query: 0.280142ms
 STATS: Query: /child::site/child::people/child::person[ child::profile/child::gender and child::profile/child::age ]/child::name 
 STATS: Automaton: 
-STATS: evaluating query: 166.081190ms
-STATS: serializing results: 2.472162ms
+STATS: evaluating query: 305.827141ms
+STATS: serializing results: 0.275135ms
 STATS: 2 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 2174 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -83,13 +83,13 @@ STATS: cache4: length: 2385, used: 211, occupation: 0.088470
 Diff: ok
 -------------------------------------------
 Query: A7 : /site/people/person[phone or homepage]/name
-STATS: parsing xml document: 192.768097ms
-STATS: parsing XPath query: 0.132084ms
-STATS: compiling XPath query: 0.111103ms
+STATS: parsing xml document: 405.750990ms
+STATS: parsing XPath query: 0.069857ms
+STATS: compiling XPath query: 0.235081ms
 STATS: Query: /child::site/child::people/child::person[ child::phone or child::homepage ]/child::name 
 STATS: Automaton: 
-STATS: evaluating query: 162.614822ms
-STATS: serializing results: 4.990816ms
+STATS: evaluating query: 309.036016ms
+STATS: serializing results: 1.051903ms
 STATS: 2 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 1728 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -97,13 +97,13 @@ STATS: cache4: length: 1927, used: 199, occupation: 0.103269
 Diff: ok
 -------------------------------------------
 Query: A8 : /site/people/person[address and (phone or homepage) and (creditcard or profile)]/name
-STATS: parsing xml document: 191.587925ms
-STATS: parsing XPath query: 0.117064ms
-STATS: compiling XPath query: 0.157118ms
+STATS: parsing xml document: 417.298794ms
+STATS: parsing XPath query: 0.096083ms
+STATS: compiling XPath query: 0.298977ms
 STATS: Query: /child::site/child::people/child::person[ child::address and (child::phone or child::homepage) and (child::creditcard or child::profile) ]/child::name 
 STATS: Automaton: 
-STATS: evaluating query: 175.364017ms
-STATS: serializing results: 3.123999ms
+STATS: evaluating query: 325.822115ms
+STATS: serializing results: 0.463009ms
 STATS: 2 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 9331 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -111,13 +111,13 @@ STATS: cache4: length: 10253, used: 922, occupation: 0.089925
 Diff: ok
 -------------------------------------------
 Query: B1 : /site/regions/*/item[parent::namerica or parent::samerica]/name
-STATS: parsing xml document: 192.234039ms
-STATS: parsing XPath query: 0.096083ms
-STATS: compiling XPath query: 0.241041ms
+STATS: parsing xml document: 411.556959ms
+STATS: parsing XPath query: 0.079870ms
+STATS: compiling XPath query: 0.313044ms
 STATS: Query: /child::site/child::regions/child::*/child::item[ parent::namerica or parent::samerica ]/child::name 
 STATS: Automaton: 
-STATS: evaluating query: 110.785961ms
-STATS: serializing results: 3.708839ms
+STATS: evaluating query: 215.039015ms
+STATS: serializing results: 0.742912ms
 STATS: 1 iterations
 STATS: automaton 0, cache2: 170 entries, cache6: 3466 entries
 STATS: cache2: length: 255, used: 85, occupation: 0.333333
@@ -125,13 +125,13 @@ STATS: cache4: length: 3760, used: 294, occupation: 0.078191
 Diff: ok
 -------------------------------------------
 Query: B2 : //keyword/ancestor::listitem/text/keyword
-STATS: parsing xml document: 191.808939ms
-STATS: parsing XPath query: 0.081062ms
-STATS: compiling XPath query: 0.133038ms
+STATS: parsing xml document: 410.917044ms
+STATS: parsing XPath query: 0.068188ms
+STATS: compiling XPath query: 0.227928ms
 STATS: Query: /descendant-or-self::node()/child::keyword/ancestor::listitem/child::text/child::keyword 
 STATS: Automaton: 
-STATS: evaluating query: 188.368082ms
-STATS: serializing results: 13.005018ms
+STATS: evaluating query: 349.317789ms
+STATS: serializing results: 2.888918ms
 STATS: 2 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 2331 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -139,13 +139,13 @@ STATS: cache4: length: 2619, used: 288, occupation: 0.109966
 Diff: ok
 -------------------------------------------
 Query: B3 : /site/open_auctions/open_auction/bidder[following-sibling::bidder]
-STATS: parsing xml document: 190.916777ms
-STATS: parsing XPath query: 0.074863ms
-STATS: compiling XPath query: 0.138044ms
+STATS: parsing xml document: 401.097775ms
+STATS: parsing XPath query: 0.061989ms
+STATS: compiling XPath query: 0.203848ms
 STATS: Query: /child::site/child::open_auctions/child::open_auction/child::bidder[ following-sibling::bidder ] 
 STATS: Automaton: 
-STATS: evaluating query: 126.197100ms
-STATS: serializing results: 31.688929ms
+STATS: evaluating query: 226.150036ms
+STATS: serializing results: 7.114887ms
 STATS: 1 iterations
 STATS: automaton 0, cache2: 170 entries, cache6: 2871 entries
 STATS: cache2: length: 255, used: 85, occupation: 0.333333
@@ -153,13 +153,13 @@ STATS: cache4: length: 3131, used: 260, occupation: 0.083041
 Diff: ok
 -------------------------------------------
 Query: B4 : /site/open_auctions/open_auction/bidder[preceding-sibling::bidder]
-STATS: parsing xml document: 190.321922ms
-STATS: parsing XPath query: 0.083923ms
-STATS: compiling XPath query: 0.125885ms
+STATS: parsing xml document: 404.300928ms
+STATS: parsing XPath query: 0.075817ms
+STATS: compiling XPath query: 0.204086ms
 STATS: Query: /child::site/child::open_auctions/child::open_auction/child::bidder[ preceding-sibling::bidder ] 
 STATS: Automaton: 
-STATS: evaluating query: 111.509800ms
-STATS: serializing results: 31.404972ms
+STATS: evaluating query: 211.428165ms
+STATS: serializing results: 7.288933ms
 STATS: 1 iterations
 STATS: automaton 0, cache2: 170 entries, cache6: 2282 entries
 STATS: cache2: length: 255, used: 85, occupation: 0.333333
@@ -167,13 +167,13 @@ STATS: cache4: length: 2507, used: 225, occupation: 0.089749
 Diff: ok
 -------------------------------------------
 Query: B5 : /site/regions/*/item[following::item]/name
-STATS: parsing xml document: 190.927029ms
-STATS: parsing XPath query: 0.085831ms
-STATS: compiling XPath query: 0.184059ms
+STATS: parsing xml document: 405.932903ms
+STATS: parsing XPath query: 0.068903ms
+STATS: compiling XPath query: 0.341892ms
 STATS: Query: /child::site/child::regions/child::*/child::item[ ancestor-or-self::node()/following-sibling::node()/descendant-or-self::item ]/child::name 
 STATS: Automaton: 
-STATS: evaluating query: 176.085949ms
-STATS: serializing results: 5.687952ms
+STATS: evaluating query: 342.656851ms
+STATS: serializing results: 1.129150ms
 STATS: 2 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 1992 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -181,13 +181,13 @@ STATS: cache4: length: 2302, used: 310, occupation: 0.134666
 Diff: ok
 -------------------------------------------
 Query: B6 : /site/regions/*/item[preceding::item]/name
-STATS: parsing xml document: 191.268921ms
-STATS: parsing XPath query: 0.084877ms
-STATS: compiling XPath query: 0.183105ms
+STATS: parsing xml document: 401.692867ms
+STATS: parsing XPath query: 0.070095ms
+STATS: compiling XPath query: 0.338078ms
 STATS: Query: /child::site/child::regions/child::*/child::item[ ancestor-or-self::node()/preceding-sibling::node()/descendant-or-self::item ]/child::name 
 STATS: Automaton: 
-STATS: evaluating query: 131.505966ms
-STATS: serializing results: 5.664110ms
+STATS: evaluating query: 250.946999ms
+STATS: serializing results: 1.174927ms
 STATS: 1 iterations
 STATS: automaton 0, cache2: 170 entries, cache6: 4419 entries
 STATS: cache2: length: 255, used: 85, occupation: 0.333333
@@ -195,13 +195,13 @@ STATS: cache4: length: 4800, used: 381, occupation: 0.079375
 Diff: ok
 -------------------------------------------
 Query: B7 : //person[profile/@income]/name
-STATS: parsing xml document: 192.240000ms
-STATS: parsing XPath query: 0.082016ms
-STATS: compiling XPath query: 0.117064ms
+STATS: parsing xml document: 422.059774ms
+STATS: parsing XPath query: 0.068188ms
+STATS: compiling XPath query: 0.185966ms
 STATS: Query: /descendant-or-self::node()/child::person[ child::profile/attribute::@income ]/child::name 
 STATS: Automaton: 
-STATS: evaluating query: 170.628071ms
-STATS: serializing results: 3.857851ms
+STATS: evaluating query: 330.724001ms
+STATS: serializing results: 0.678062ms
 STATS: 2 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 1238 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -209,13 +209,13 @@ STATS: cache4: length: 1349, used: 111, occupation: 0.082283
 Diff: ok
 -------------------------------------------
 Query: B8 : /site/open_auctions/open_auction[bidder and not(bidder/preceding-sibling::bidder)]/interval
-STATS: parsing xml document: 188.287020ms
-STATS: parsing XPath query: 0.106096ms
-STATS: compiling XPath query: 0.140905ms
+STATS: parsing xml document: 404.323101ms
+STATS: parsing XPath query: 0.087023ms
+STATS: compiling XPath query: 0.262022ms
 STATS: Query: /child::site/child::open_auctions/child::open_auction[ child::bidder and not(child::bidder/preceding-sibling::bidder) ]/child::interval 
 STATS: Automaton: 
-STATS: evaluating query: 171.743155ms
-STATS: serializing results: 2.464056ms
+STATS: evaluating query: 319.082022ms
+STATS: serializing results: 0.202894ms
 STATS: 2 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 1052 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -223,13 +223,13 @@ STATS: cache4: length: 1229, used: 177, occupation: 0.144020
 Diff: ok
 -------------------------------------------
 Query: B9 : /site/open_auctions/open_auction[(not(bidder/following::bidder) or not(bidder/preceding::bidder)) or (bidder/following::bidder and bidder/preceding::bidder)]/interval
-STATS: parsing xml document: 186.557055ms
-STATS: parsing XPath query: 0.151157ms
-STATS: compiling XPath query: 0.347853ms
+STATS: parsing xml document: 410.195112ms
+STATS: parsing XPath query: 0.121832ms
+STATS: compiling XPath query: 0.746012ms
 STATS: Query: /child::site/child::open_auctions/child::open_auction[ not(child::bidder/ancestor-or-self::node()/following-sibling::node()/descendant-or-self::bidder) or not(child::bidder/ancestor-or-self::node()/preceding-sibling::node()/descendant-or-self::bidder) or child::bidder/ancestor-or-self::node()/following-sibling::node()/descendant-or-self::bidder and child::bidder/ancestor-or-self::node()/preceding-sibling::node()/descendant-or-self::bidder ]/child::interval 
 STATS: Automaton: 
-STATS: evaluating query: 249.915838ms
-STATS: serializing results: 6.402969ms
+STATS: evaluating query: 474.653959ms
+STATS: serializing results: 1.121044ms
 STATS: 3 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 786 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -237,13 +237,13 @@ STATS: cache4: length: 976, used: 190, occupation: 0.194672
 Diff: ok
 -------------------------------------------
 Query: B10 : /site/open_auctions/open_auction[(not(bidder/following::bidder) or not(bidder/preceding::bidder)) and (bidder/following::bidder and bidder/preceding::bidder)]/interval
-STATS: parsing xml document: 191.452980ms
-STATS: parsing XPath query: 0.135183ms
-STATS: compiling XPath query: 0.352144ms
+STATS: parsing xml document: 403.446913ms
+STATS: parsing XPath query: 0.122070ms
+STATS: compiling XPath query: 0.746012ms
 STATS: Query: /child::site/child::open_auctions/child::open_auction[ (not(child::bidder/ancestor-or-self::node()/following-sibling::node()/descendant-or-self::bidder) or not(child::bidder/ancestor-or-self::node()/preceding-sibling::node()/descendant-or-self::bidder)) and child::bidder/ancestor-or-self::node()/following-sibling::node()/descendant-or-self::bidder and child::bidder/ancestor-or-self::node()/preceding-sibling::node()/descendant-or-self::bidder ]/child::interval 
 STATS: Automaton: 
-STATS: evaluating query: 239.425898ms
-STATS: serializing results: 1.636982ms
+STATS: evaluating query: 487.916946ms
+STATS: serializing results: 0.033140ms
 STATS: 3 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 827 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -251,13 +251,13 @@ STATS: cache4: length: 1000, used: 173, occupation: 0.173000
 Diff: ok
 -------------------------------------------
 Query: B11 : //open_auction/bidder/../bidder/../bidder/../interval
-STATS: parsing xml document: 189.797163ms
-STATS: parsing XPath query: 0.072956ms
-STATS: compiling XPath query: 0.228882ms
+STATS: parsing xml document: 404.007912ms
+STATS: parsing XPath query: 0.060081ms
+STATS: compiling XPath query: 0.418186ms
 STATS: Query: /descendant-or-self::node()/child::open_auction/child::bidder/parent::node()/child::bidder/parent::node()/child::bidder/parent::node()/child::interval 
 STATS: Automaton: 
-STATS: evaluating query: 276.601076ms
-STATS: serializing results: 6.062984ms
+STATS: evaluating query: 537.139177ms
+STATS: serializing results: 1.827955ms
 STATS: 4 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 598 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -265,13 +265,13 @@ STATS: cache4: length: 708, used: 110, occupation: 0.155367
 Diff: ok
 -------------------------------------------
 Query: B12 : //item/@id/../@id/../@id/../@id/../name
-STATS: parsing xml document: 191.452026ms
-STATS: parsing XPath query: 0.083923ms
-STATS: compiling XPath query: 0.275850ms
+STATS: parsing xml document: 399.324894ms
+STATS: parsing XPath query: 0.067949ms
+STATS: compiling XPath query: 0.534058ms
 STATS: Query: /descendant-or-self::node()/child::item/attribute::@id/parent::node()/attribute::@id/parent::node()/attribute::@id/parent::node()/attribute::@id/parent::node()/child::name 
 STATS: Automaton: 
-STATS: evaluating query: 328.938007ms
-STATS: serializing results: 5.661011ms
+STATS: evaluating query: 624.691963ms
+STATS: serializing results: 1.193047ms
 STATS: 5 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 870 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan
@@ -279,13 +279,13 @@ STATS: cache4: length: 993, used: 123, occupation: 0.123867
 Diff: ok
 -------------------------------------------
 Query: B13 : //keyword/ancestor::parlist/descendant::keyword/ancestor::parlist/descendant::keyword/ancestor::parlist/descendant::keyword
-STATS: parsing xml document: 188.703060ms
-STATS: parsing XPath query: 0.082970ms
-STATS: compiling XPath query: 0.232935ms
+STATS: parsing xml document: 419.275045ms
+STATS: parsing XPath query: 0.081062ms
+STATS: compiling XPath query: 0.416994ms
 STATS: Query: /descendant-or-self::node()/child::keyword/ancestor::parlist/descendant::keyword/ancestor::parlist/descendant::keyword/ancestor::parlist/descendant::keyword 
 STATS: Automaton: 
-STATS: evaluating query: 286.443949ms
-STATS: serializing results: 14.273882ms
+STATS: evaluating query: 531.944990ms
+STATS: serializing results: 3.096819ms
 STATS: 4 iterations
 STATS: automaton 0, cache2: 0 entries, cache6: 2671 entries
 STATS: cache2: length: 0, used: 0, occupation: -nan