X-Git-Url: http://git.nguyen.vg/gitweb/?p=tatoo.git;a=blobdiff_plain;f=tests%2Fxmark_small.xml.summary;h=955e7ce89485082fac853e9881c155a21ad05e39;hp=b3daa4fa0da3b9b6bfa3fd1560171303c6f8c9aa;hb=acd23a33d837d03c813212ec3896a86edef16e87;hpb=ff22f9cdf128206981144d94644a02caa70ad873 diff --git a/tests/xmark_small.xml.summary b/tests/xmark_small.xml.summary index b3daa4f..955e7ce 100644 --- a/tests/xmark_small.xml.summary +++ b/tests/xmark_small.xml.summary @@ -1,11 +1,13 @@ Query: A1 : /site/closed_auctions/closed_auction/annotation/description/text/keyword -STATS: parsing xml document: 188.066959ms -STATS: parsing XPath query: 0.082970ms -STATS: compiling XPath query: 3.010988ms +STATS: parsing xml document: 634.769201ms +STATS: parsing XPath query: 0.095844ms +STATS: compiling XPath query: 0.281096ms STATS: Query: /child::site/child::closed_auctions/child::closed_auction/child::annotation/child::description/child::text/child::keyword STATS: Automaton: -STATS: evaluating query: 109.679937ms -STATS: serializing results: 3.473043ms +STATS: 254301 nodes over 254301 were skipped in iteration 0 (100.00 %), redo is: false +STATS: evaluating query: 119.843006ms +STATS: serializing results: 3.608942ms +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false STATS: 1 iterations STATS: automaton 0, cache2: 170 entries, cache6: 3149 entries STATS: cache2: length: 255, used: 85, occupation: 0.333333 @@ -13,13 +15,15 @@ STATS: cache4: length: 3464, used: 315, occupation: 0.090935 Diff: ok ------------------------------------------- Query: A2 : //closed_auction//keyword -STATS: parsing xml document: 189.096928ms -STATS: parsing XPath query: 0.035048ms -STATS: compiling XPath query: 2.774000ms +STATS: parsing xml document: 191.257000ms +STATS: parsing XPath query: 0.056028ms +STATS: compiling XPath query: 0.144005ms STATS: Query: /descendant-or-self::node()/child::closed_auction/descendant-or-self::node()/child::keyword STATS: Automaton: -STATS: evaluating query: 130.786896ms -STATS: serializing results: 6.608963ms +STATS: 254301 nodes over 254301 were skipped in iteration 0 (100.00 %), redo is: false +STATS: evaluating query: 130.471945ms +STATS: serializing results: 6.496906ms +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false STATS: 1 iterations STATS: automaton 0, cache2: 170 entries, cache6: 2681 entries STATS: cache2: length: 255, used: 85, occupation: 0.333333 @@ -27,13 +31,15 @@ STATS: cache4: length: 2915, used: 234, occupation: 0.080274 Diff: ok ------------------------------------------- Query: A3 : /site/closed_auctions/closed_auction//keyword -STATS: parsing xml document: 185.206890ms -STATS: parsing XPath query: 0.056028ms -STATS: compiling XPath query: 2.818108ms +STATS: parsing xml document: 189.831018ms +STATS: parsing XPath query: 0.063896ms +STATS: compiling XPath query: 0.157118ms STATS: Query: /child::site/child::closed_auctions/child::closed_auction/descendant-or-self::node()/child::keyword STATS: Automaton: -STATS: evaluating query: 118.218184ms -STATS: serializing results: 6.602049ms +STATS: 254301 nodes over 254301 were skipped in iteration 0 (100.00 %), redo is: false +STATS: evaluating query: 139.882088ms +STATS: serializing results: 6.744862ms +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false STATS: 1 iterations STATS: automaton 0, cache2: 170 entries, cache6: 3277 entries STATS: cache2: length: 255, used: 85, occupation: 0.333333 @@ -41,83 +47,100 @@ 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: 183.023930ms -STATS: parsing XPath query: 0.104189ms -STATS: compiling XPath query: 2.968073ms +STATS: parsing xml document: 189.064026ms +STATS: parsing XPath query: 0.106096ms +STATS: compiling XPath query: 0.199080ms 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: 158.648968ms -STATS: serializing results: 1.986980ms +STATS: 245034 nodes over 254301 were skipped in iteration 0 (96.36 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false +STATS: evaluating query: 135.497093ms +STATS: serializing results: 2.182007ms +STATS: 254301 nodes over 254301 were skipped in iteration 2 (100.00 %), redo is: false STATS: 2 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 1324 entries +STATS: automaton 0, cache2: 0 entries, cache6: 282 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 1501, used: 177, occupation: 0.117921 +STATS: cache4: length: 355, used: 73, occupation: 0.205634 Diff: ok ------------------------------------------- Query: A5 : /site/closed_auctions/closed_auction[descendant::keyword]/date -STATS: parsing xml document: 191.606045ms -STATS: parsing XPath query: 0.076056ms -STATS: compiling XPath query: 2.912998ms +STATS: parsing xml document: 190.711021ms +STATS: parsing XPath query: 0.084162ms +STATS: compiling XPath query: 0.134945ms STATS: Query: /child::site/child::closed_auctions/child::closed_auction[ descendant::keyword ]/child::date STATS: Automaton: -STATS: evaluating query: 161.002159ms -STATS: serializing results: 2.372026ms +STATS: 245034 nodes over 254301 were skipped in iteration 0 (96.36 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false +STATS: evaluating query: 138.205051ms +STATS: serializing results: 2.486944ms +STATS: 254301 nodes over 254301 were skipped in iteration 2 (100.00 %), redo is: false STATS: 2 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 1170 entries +STATS: automaton 0, cache2: 0 entries, cache6: 259 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 1328, used: 158, occupation: 0.118976 +STATS: cache4: length: 342, used: 83, occupation: 0.242690 Diff: ok ------------------------------------------- Query: A6 : /site/people/person[profile/gender and profile/age]/name -STATS: parsing xml document: 184.432983ms -STATS: parsing XPath query: 0.099897ms -STATS: compiling XPath query: 2.846003ms +STATS: parsing xml document: 185.791016ms +STATS: parsing XPath query: 0.102043ms +STATS: compiling XPath query: 0.155210ms STATS: Query: /child::site/child::people/child::person[ child::profile/child::gender and child::profile/child::age ]/child::name STATS: Automaton: -STATS: evaluating query: 158.978224ms -STATS: serializing results: 2.426863ms +STATS: 236309 nodes over 254301 were skipped in iteration 0 (92.92 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false +STATS: evaluating query: 149.569035ms +STATS: serializing results: 2.363920ms +STATS: 254301 nodes over 254301 were skipped in iteration 2 (100.00 %), redo is: false STATS: 2 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 2174 entries +STATS: automaton 0, cache2: 0 entries, cache6: 1570 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 2385, used: 211, occupation: 0.088470 +STATS: cache4: length: 1719, used: 149, occupation: 0.086678 Diff: ok ------------------------------------------- Query: A7 : /site/people/person[phone or homepage]/name -STATS: parsing xml document: 191.473007ms +STATS: parsing xml document: 187.725067ms STATS: parsing XPath query: 0.087023ms -STATS: compiling XPath query: 3.005981ms +STATS: compiling XPath query: 0.158072ms STATS: Query: /child::site/child::people/child::person[ child::phone or child::homepage ]/child::name STATS: Automaton: -STATS: evaluating query: 155.133009ms -STATS: serializing results: 4.827976ms +STATS: 236309 nodes over 254301 were skipped in iteration 0 (92.92 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false +STATS: evaluating query: 140.325069ms +STATS: serializing results: 4.892111ms +STATS: 254301 nodes over 254301 were skipped in iteration 2 (100.00 %), redo is: false STATS: 2 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 1728 entries +STATS: automaton 0, cache2: 0 entries, cache6: 1257 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 1927, used: 199, occupation: 0.103269 +STATS: cache4: length: 1420, used: 163, occupation: 0.114789 Diff: ok ------------------------------------------- Query: A8 : /site/people/person[address and (phone or homepage) and (creditcard or profile)]/name -STATS: parsing xml document: 182.379961ms -STATS: parsing XPath query: 0.100136ms -STATS: compiling XPath query: 2.874136ms +STATS: parsing xml document: 186.979055ms +STATS: parsing XPath query: 0.118971ms +STATS: compiling XPath query: 0.159979ms 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: 164.626122ms -STATS: serializing results: 3.145933ms +STATS: 236309 nodes over 254301 were skipped in iteration 0 (92.92 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false +STATS: evaluating query: 150.840998ms +STATS: serializing results: 3.206968ms +STATS: 254301 nodes over 254301 were skipped in iteration 2 (100.00 %), redo is: false STATS: 2 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 9331 entries +STATS: automaton 0, cache2: 0 entries, cache6: 7979 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 10253, used: 922, occupation: 0.089925 +STATS: cache4: length: 8812, used: 833, occupation: 0.094530 Diff: ok ------------------------------------------- Query: B1 : /site/regions/*/item[parent::namerica or parent::samerica]/name -STATS: parsing xml document: 193.360090ms -STATS: parsing XPath query: 0.061989ms -STATS: compiling XPath query: 3.045082ms +STATS: parsing xml document: 191.373110ms +STATS: parsing XPath query: 0.096083ms +STATS: compiling XPath query: 0.161171ms STATS: Query: /child::site/child::regions/child::*/child::item[ parent::namerica or parent::samerica ]/child::name STATS: Automaton: -STATS: evaluating query: 117.424965ms -STATS: serializing results: 3.699064ms +STATS: 254301 nodes over 254301 were skipped in iteration 0 (100.00 %), redo is: false +STATS: evaluating query: 119.151115ms +STATS: serializing results: 3.636837ms +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false STATS: 1 iterations STATS: automaton 0, cache2: 170 entries, cache6: 3466 entries STATS: cache2: length: 255, used: 85, occupation: 0.333333 @@ -125,27 +148,32 @@ STATS: cache4: length: 3760, used: 294, occupation: 0.078191 Diff: ok ------------------------------------------- Query: B2 : //keyword/ancestor::listitem/text/keyword -STATS: parsing xml document: 194.656134ms -STATS: parsing XPath query: 0.077963ms -STATS: compiling XPath query: 2.819061ms +STATS: parsing xml document: 191.718102ms +STATS: parsing XPath query: 0.073195ms +STATS: compiling XPath query: 0.213861ms STATS: Query: /descendant-or-self::node()/child::keyword/ancestor::listitem/child::text/child::keyword STATS: Automaton: -STATS: evaluating query: 185.279131ms -STATS: serializing results: 13.365030ms +STATS: 210583 nodes over 254301 were skipped in iteration 0 (82.81 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false +STATS: evaluating query: 166.561127ms +STATS: serializing results: 13.072968ms +STATS: 254301 nodes over 254301 were skipped in iteration 2 (100.00 %), redo is: false STATS: 2 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 2331 entries +STATS: automaton 0, cache2: 0 entries, cache6: 1811 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 2619, used: 288, occupation: 0.109966 +STATS: cache4: length: 2050, used: 239, occupation: 0.116585 Diff: ok ------------------------------------------- Query: B3 : /site/open_auctions/open_auction/bidder[following-sibling::bidder] -STATS: parsing xml document: 188.459873ms -STATS: parsing XPath query: 0.063896ms -STATS: compiling XPath query: 2.804995ms +STATS: parsing xml document: 191.766024ms +STATS: parsing XPath query: 0.077009ms +STATS: compiling XPath query: 0.133991ms STATS: Query: /child::site/child::open_auctions/child::open_auction/child::bidder[ following-sibling::bidder ] STATS: Automaton: -STATS: evaluating query: 116.722822ms -STATS: serializing results: 31.981945ms +STATS: 254301 nodes over 254301 were skipped in iteration 0 (100.00 %), redo is: false +STATS: evaluating query: 130.250931ms +STATS: serializing results: 31.550884ms +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false STATS: 1 iterations STATS: automaton 0, cache2: 170 entries, cache6: 2871 entries STATS: cache2: length: 255, used: 85, occupation: 0.333333 @@ -153,13 +181,15 @@ 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: 193.113804ms -STATS: parsing XPath query: 0.078917ms -STATS: compiling XPath query: 2.804041ms +STATS: parsing xml document: 189.858913ms +STATS: parsing XPath query: 0.082970ms +STATS: compiling XPath query: 0.122070ms STATS: Query: /child::site/child::open_auctions/child::open_auction/child::bidder[ preceding-sibling::bidder ] STATS: Automaton: -STATS: evaluating query: 109.422922ms -STATS: serializing results: 31.900167ms +STATS: 254301 nodes over 254301 were skipped in iteration 0 (100.00 %), redo is: false +STATS: evaluating query: 117.531061ms +STATS: serializing results: 31.703949ms +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false STATS: 1 iterations STATS: automaton 0, cache2: 170 entries, cache6: 2282 entries STATS: cache2: length: 255, used: 85, occupation: 0.333333 @@ -167,27 +197,32 @@ STATS: cache4: length: 2507, used: 225, occupation: 0.089749 Diff: ok ------------------------------------------- Query: B5 : /site/regions/*/item[following::item]/name -STATS: parsing xml document: 186.527967ms -STATS: parsing XPath query: 0.079870ms -STATS: compiling XPath query: 3.057957ms +STATS: parsing xml document: 186.423779ms +STATS: parsing XPath query: 0.076056ms +STATS: compiling XPath query: 0.211000ms 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: 173.794985ms -STATS: serializing results: 5.778074ms +STATS: 1 nodes over 254301 were skipped in iteration 0 (0.00 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false +STATS: evaluating query: 200.829983ms +STATS: serializing results: 5.727053ms +STATS: 254301 nodes over 254301 were skipped in iteration 2 (100.00 %), redo is: false STATS: 2 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 1992 entries +STATS: automaton 0, cache2: 0 entries, cache6: 1990 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 2302, used: 310, occupation: 0.134666 +STATS: cache4: length: 2299, used: 309, occupation: 0.134406 Diff: ok ------------------------------------------- Query: B6 : /site/regions/*/item[preceding::item]/name -STATS: parsing xml document: 187.946796ms -STATS: parsing XPath query: 0.082970ms -STATS: compiling XPath query: 2.902031ms +STATS: parsing xml document: 187.946081ms +STATS: parsing XPath query: 0.046968ms +STATS: compiling XPath query: 0.171900ms 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: 127.434969ms -STATS: serializing results: 5.630016ms +STATS: 254301 nodes over 254301 were skipped in iteration 0 (100.00 %), redo is: false +STATS: evaluating query: 140.237093ms +STATS: serializing results: 5.573988ms +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false STATS: 1 iterations STATS: automaton 0, cache2: 170 entries, cache6: 4419 entries STATS: cache2: length: 255, used: 85, occupation: 0.333333 @@ -195,100 +230,130 @@ STATS: cache4: length: 4800, used: 381, occupation: 0.079375 Diff: ok ------------------------------------------- Query: B7 : //person[profile/@income]/name -STATS: parsing xml document: 184.038877ms -STATS: parsing XPath query: 0.066042ms -STATS: compiling XPath query: 2.686024ms +STATS: parsing xml document: 192.195177ms +STATS: parsing XPath query: 0.074148ms +STATS: compiling XPath query: 0.116110ms STATS: Query: /descendant-or-self::node()/child::person[ child::profile/attribute::@income ]/child::name STATS: Automaton: -STATS: evaluating query: 165.220976ms -STATS: serializing results: 3.939152ms +STATS: 236309 nodes over 254301 were skipped in iteration 0 (92.92 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false +STATS: evaluating query: 145.928860ms +STATS: serializing results: 4.064083ms +STATS: 254301 nodes over 254301 were skipped in iteration 2 (100.00 %), redo is: false STATS: 2 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 1238 entries +STATS: automaton 0, cache2: 0 entries, cache6: 624 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 1349, used: 111, occupation: 0.082283 +STATS: cache4: length: 705, used: 81, occupation: 0.114894 Diff: ok ------------------------------------------- Query: B8 : /site/open_auctions/open_auction[bidder and not(bidder/preceding-sibling::bidder)]/interval -STATS: parsing xml document: 187.812090ms -STATS: parsing XPath query: 0.101089ms -STATS: compiling XPath query: 2.710104ms +STATS: parsing xml document: 189.630985ms +STATS: parsing XPath query: 0.108004ms +STATS: compiling XPath query: 0.137091ms 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: 157.864809ms -STATS: serializing results: 2.645969ms +STATS: 235063 nodes over 254301 were skipped in iteration 0 (92.43 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 1 (100.00 %), redo is: false +STATS: evaluating query: 140.723944ms +STATS: serializing results: 2.576828ms +STATS: 254301 nodes over 254301 were skipped in iteration 2 (100.00 %), redo is: false STATS: 2 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 1052 entries +STATS: automaton 0, cache2: 0 entries, cache6: 368 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 1229, used: 177, occupation: 0.144020 +STATS: cache4: length: 506, used: 138, occupation: 0.272727 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: 190.474033ms -STATS: parsing XPath query: 0.148058ms -STATS: compiling XPath query: 3.069878ms +STATS: parsing xml document: 180.969000ms +STATS: parsing XPath query: 0.151157ms +STATS: compiling XPath query: 0.355959ms 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: 242.839098ms -STATS: serializing results: 6.402969ms +STATS: 1 nodes over 254301 were skipped in iteration 0 (0.00 %), redo is: true +STATS: 251143 nodes over 254301 were skipped in iteration 1 (98.76 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 2 (100.00 %), redo is: false +STATS: evaluating query: 224.873066ms +STATS: serializing results: 6.433010ms +STATS: 254301 nodes over 254301 were skipped in iteration 3 (100.00 %), redo is: false STATS: 3 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 786 entries +STATS: automaton 0, cache2: 0 entries, cache6: 157 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 976, used: 190, occupation: 0.194672 +STATS: cache4: length: 213, used: 56, occupation: 0.262911 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: 187.290192ms -STATS: parsing XPath query: 0.123024ms -STATS: compiling XPath query: 3.057957ms +STATS: parsing xml document: 183.919191ms +STATS: parsing XPath query: 0.127792ms +STATS: compiling XPath query: 0.363827ms 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: 238.831997ms -STATS: serializing results: 1.582861ms +STATS: 1 nodes over 254301 were skipped in iteration 0 (0.00 %), redo is: true +STATS: 251143 nodes over 254301 were skipped in iteration 1 (98.76 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 2 (100.00 %), redo is: false +STATS: evaluating query: 221.321106ms +STATS: serializing results: 1.529217ms +STATS: 254301 nodes over 254301 were skipped in iteration 3 (100.00 %), redo is: false STATS: 3 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 827 entries +STATS: automaton 0, cache2: 0 entries, cache6: 153 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 1000, used: 173, occupation: 0.173000 +STATS: cache4: length: 208, used: 55, occupation: 0.264423 Diff: ok ------------------------------------------- Query: B11 : //open_auction/bidder/../bidder/../bidder/../interval -STATS: parsing xml document: 184.998989ms -STATS: parsing XPath query: 0.063181ms -STATS: compiling XPath query: 2.854109ms +STATS: parsing xml document: 190.995932ms +STATS: parsing XPath query: 0.072956ms +STATS: compiling XPath query: 0.244856ms 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: 264.686108ms -STATS: serializing results: 5.999088ms +STATS: 0 nodes over 254301 were skipped in iteration 0 (0.00 %), redo is: true +STATS: 236211 nodes over 254301 were skipped in iteration 1 (92.89 %), redo is: true +STATS: 236211 nodes over 254301 were skipped in iteration 2 (92.89 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 3 (100.00 %), redo is: false +STATS: evaluating query: 225.605011ms +STATS: serializing results: 6.007910ms +STATS: 254301 nodes over 254301 were skipped in iteration 4 (100.00 %), redo is: false STATS: 4 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 598 entries +STATS: automaton 0, cache2: 0 entries, cache6: 144 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 708, used: 110, occupation: 0.155367 +STATS: cache4: length: 209, used: 65, occupation: 0.311005 Diff: ok ------------------------------------------- Query: B12 : //item/@id/../@id/../@id/../@id/../name -STATS: parsing xml document: 181.235075ms -STATS: parsing XPath query: 0.097990ms -STATS: compiling XPath query: 3.005028ms +STATS: parsing xml document: 194.081068ms +STATS: parsing XPath query: 0.083923ms +STATS: compiling XPath query: 0.293016ms 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: 324.455023ms -STATS: serializing results: 5.921125ms +STATS: 0 nodes over 254301 were skipped in iteration 0 (0.00 %), redo is: true +STATS: 188718 nodes over 254301 were skipped in iteration 1 (74.21 %), redo is: true +STATS: 188718 nodes over 254301 were skipped in iteration 2 (74.21 %), redo is: true +STATS: 188718 nodes over 254301 were skipped in iteration 3 (74.21 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 4 (100.00 %), redo is: false +STATS: evaluating query: 266.115189ms +STATS: serializing results: 5.604982ms +STATS: 254301 nodes over 254301 were skipped in iteration 5 (100.00 %), redo is: false STATS: 5 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 870 entries +STATS: automaton 0, cache2: 0 entries, cache6: 416 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 993, used: 123, occupation: 0.123867 +STATS: cache4: length: 524, used: 108, occupation: 0.206107 Diff: ok ------------------------------------------- Query: B13 : //keyword/ancestor::parlist/descendant::keyword/ancestor::parlist/descendant::keyword/ancestor::parlist/descendant::keyword -STATS: parsing xml document: 185.913086ms -STATS: parsing XPath query: 0.092030ms -STATS: compiling XPath query: 3.149986ms +STATS: parsing xml document: 183.907986ms +STATS: parsing XPath query: 0.083923ms +STATS: compiling XPath query: 0.251055ms 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: 278.827190ms -STATS: serializing results: 14.353991ms +STATS: 203073 nodes over 254301 were skipped in iteration 0 (79.86 %), redo is: true +STATS: 208107 nodes over 254301 were skipped in iteration 1 (81.83 %), redo is: true +STATS: 208107 nodes over 254301 were skipped in iteration 2 (81.83 %), redo is: true +STATS: 254301 nodes over 254301 were skipped in iteration 3 (100.00 %), redo is: false +STATS: evaluating query: 197.850943ms +STATS: serializing results: 14.370203ms +STATS: 254301 nodes over 254301 were skipped in iteration 4 (100.00 %), redo is: false STATS: 4 iterations -STATS: automaton 0, cache2: 0 entries, cache6: 2671 entries +STATS: automaton 0, cache2: 0 entries, cache6: 981 entries STATS: cache2: length: 0, used: 0, occupation: -nan -STATS: cache4: length: 2984, used: 313, occupation: 0.104893 +STATS: cache4: length: 1198, used: 217, occupation: 0.181135 Diff: ok -------------------------------------------