Report for Tie-CacheHash-0.50

Back
From: metabase:user:3198cbe2-2aae-11df-837a-5e0a49663a4f
Subject: PASS Tie-CacheHash-0.50 v5.18.0 GNU/Linux
Date: 2014-02-04T17:24:23Z

This distribution has been tested as part of the CPAN Testers
project, supporting the Perl programming language.  See
http://wiki.cpantesters.org/ for more information or email
questions to cpan-testers-discuss@perl.org


--
Dear Jamie McCarthy,

This is a computer-generated report for Tie-CacheHash-0.50
on perl 5.18.0, created by CPAN-Reporter-1.2010.

Thank you for uploading your work to CPAN.  Congratulations!
All tests were successful.

Sections of this report:

    * Tester comments
    * Program output
    * Prerequisites
    * Environment and other context

------------------------------
TESTER COMMENTS
------------------------------

Additional comments from tester:

this report is from an automated smoke testing program
and was not reviewed by a human for accuracy

------------------------------
PROGRAM OUTPUT
------------------------------

Output from '/usr/bin/make test':

PERL_DL_NONLAZY=1 /home/graham/perls/perl5.18.0-thread/bin/perl "-MExtUtils::Command::MM" "-MTest::Harness" "-e" "undef *Test::Harness::Switches; test_harness(0, 'blib/lib', 'blib/arch')" t/*.t
Tue Feb  4 17:24:18 2014 using reference to existing hash: num_keys=(not_yet_defined) ref=HASH ref(tied())=DB_File
Tue Feb  4 17:24:18 2014 cache_rebuild -1: 
Tue Feb  4 17:24:18 2014 rebuilt cache (0/0 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:18 2014 using reference to existing hash: num_keys=(not_yet_defined) ref=HASH ref(tied())=DB_File
Tue Feb  4 17:24:18 2014 cache_rebuild 379: abc123 key1 key10 key11 ... key987 key988 key989 key990
Tue Feb  4 17:24:18 2014 MONTE1 num_to_scan=800 frac=0.115789473684211 $#scanned=-1 $#unscanned=379 num_keys=380 $#$cr=-1
Tue Feb  4 17:24:18 2014 MONTE1a start_scanned=-1 num_to_scan=190
Tue Feb  4 17:24:18 2014 MONTE2 unscanned_start=71 num_to_scan=0 $#scanned=189 $#unscanned=189
Tue Feb  4 17:24:18 2014 MONTE3 guess_max_key_index=22 guess_max_key=key291 guess_value=1509
Tue Feb  4 17:24:18 2014 MONTE4 $#$cr=46
Tue Feb  4 17:24:18 2014 MONTE5 sorting array size $#$cr=46 (max=40): key386 key388 key409 key634 key649
Tue Feb  4 17:24:18 2014 MONTE6 SUCCESS $#$cr=39: abc123 key320 key275 key300 key865
Tue Feb  4 17:24:18 2014 rebuilt cache (40/380 keys) in 0 seconds (counting the check, 0 seconds)
cachetest.t new CacheHash, num_entries=380 db_hashkeys=380
cachetest.t untied db_hash, scalar(keys)=0
cachetest.t retied db_hash, scalar(keys)=380
Tue Feb  4 17:24:20 2014 using reference to existing hash: num_keys=(not_yet_defined) ref=HASH ref(tied())=DB_File
Tue Feb  4 17:24:20 2014 cache_rebuild 379: abc123 key1 key10 key11 ... key987 key988 key989 key990
Tue Feb  4 17:24:20 2014 MONTE1 num_to_scan=800 frac=0.115789473684211 $#scanned=-1 $#unscanned=379 num_keys=380 $#$cr=-1
Tue Feb  4 17:24:20 2014 MONTE1a start_scanned=-1 num_to_scan=190
Tue Feb  4 17:24:20 2014 MONTE2 unscanned_start=27 num_to_scan=0 $#scanned=189 $#unscanned=189
Tue Feb  4 17:24:20 2014 MONTE3 guess_max_key_index=22 guess_max_key=key649 guess_value=1456
Tue Feb  4 17:24:20 2014 MONTE4 $#$cr=41
Tue Feb  4 17:24:20 2014 MONTE5 sorting array size $#$cr=41 (max=40): key296 key300 key311 key320 key331
Tue Feb  4 17:24:20 2014 MONTE6 SUCCESS $#$cr=39: abc123 key320 key275 key300 key865
Tue Feb  4 17:24:20 2014 rebuilt cache (40/380 keys) in 0 seconds (counting the check, 0 seconds)
cachetest.t new CacheHash, num_entries=380 db_hashkeys=380
cachetest.t num_entries=685 db_hashkeys=685
Tue Feb  4 17:24:21 2014 cache_rebuild 652: key0 key1 key10 key1000 ... key996 key997 key998 key999
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.0673813169984686 $#scanned=-1 $#unscanned=652 num_keys=653 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=326
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=224 num_to_scan=0 $#scanned=325 $#unscanned=326
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key984 guess_value=134
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=36
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=36 (max=40): key48 key613 key688 key828 key842
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=36: key1015 key995 key998 key419 key613
Tue Feb  4 17:24:21 2014 rebuilt cache (37/653 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 624: key0 key1 key10 key1000 ... key994 key996 key997 key999
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.0704 $#scanned=-1 $#unscanned=624 num_keys=625 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=312
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=205 num_to_scan=0 $#scanned=311 $#unscanned=312
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key975 guess_value=163
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=41
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=41 (max=40): key433 key442 key451 key648 key961
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key1026 key720 key1017 key956 key1030
Tue Feb  4 17:24:21 2014 rebuilt cache (40/625 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 593: key0 key1 key10 key1000 ... key994 key996 key997 key999
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.0740740740740741 $#scanned=-1 $#unscanned=593 num_keys=594 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=297
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=67 num_to_scan=0 $#scanned=296 $#unscanned=296
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key959 guess_value=184
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=41
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=41 (max=40): key49 key678 key740 key937 key959
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key750 key306 key338 key963 key433
Tue Feb  4 17:24:21 2014 rebuilt cache (40/594 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 562: key0 key1 key10 key1000 ... key994 key996 key997 key999
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.0781527531083481 $#scanned=-1 $#unscanned=562 num_keys=563 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=281
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=222 num_to_scan=0 $#scanned=280 $#unscanned=281
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key980 guess_value=203
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=38
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=38 (max=40): key899 key943 key965 key1008 key1022
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=38: key632 key1027 key661 key678 key937
Tue Feb  4 17:24:21 2014 rebuilt cache (39/563 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 532: key0 key1 key10 key1000 ... key994 key996 key997 key999
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.0825515947467167 $#scanned=-1 $#unscanned=532 num_keys=533 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=266
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=263 num_to_scan=0 $#scanned=265 $#unscanned=266
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key633 guess_value=2552
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=37
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=37 (max=40): key265 key337 key470 key834 key845
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=37: key343 key606 key265 key1008 key834
Tue Feb  4 17:24:21 2014 rebuilt cache (38/533 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 503: key0 key1 key10 key1000 ... key994 key996 key997 key999
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.0873015873015873 $#scanned=-1 $#unscanned=503 num_keys=504 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=252
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=52 num_to_scan=0 $#scanned=251 $#unscanned=251
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key47 guess_value=2861
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=39
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=39 (max=40): key762 key766 key850 key861 key890
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key911 key925 key446 key738 key410
Tue Feb  4 17:24:21 2014 rebuilt cache (40/504 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 472: key0 key1 key10 key1000 ... key993 key994 key997 key999
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.0930232558139535 $#scanned=-1 $#unscanned=472 num_keys=473 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=236
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=11 num_to_scan=0 $#scanned=235 $#unscanned=236
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key301 guess_value=3310
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=45
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=45 (max=40): key317 key34 key353 key375 key407
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key317 key8 key762 key766 key495
Tue Feb  4 17:24:21 2014 rebuilt cache (40/473 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 441: key0 key1 key10 key1000 ... key993 key994 key997 key999
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.0995475113122172 $#scanned=-1 $#unscanned=441 num_keys=442 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=221
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=11 num_to_scan=0 $#scanned=220 $#unscanned=220
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key39 guess_value=373
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=40
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=40 (max=40): key308 key322 key34 key366 key397
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key934 key847 key896 key312 key635
Tue Feb  4 17:24:21 2014 rebuilt cache (40/442 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 410: key0 key1 key10 key1000 ... key993 key994 key997 key999
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.10705596107056 $#scanned=-1 $#unscanned=410 num_keys=411 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=205
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=102 num_to_scan=0 $#scanned=204 $#unscanned=205
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key976 guess_value=41
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=44
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=44 (max=40): key309 key378 key385 key39 key40
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key309 key935 key604 key347 key885
Tue Feb  4 17:24:21 2014 rebuilt cache (40/411 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 379: key0 key1 key10 key1000 ... key993 key994 key997 key999
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.115789473684211 $#scanned=-1 $#unscanned=379 num_keys=380 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=190
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=108 num_to_scan=0 $#scanned=189 $#unscanned=189
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key701 guess_value=4553
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=46
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=46 (max=40): key44 key653 key693 key701 key727
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key378 key710 key372 key1025 key999
Tue Feb  4 17:24:21 2014 rebuilt cache (40/380 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 348: key0 key1 key10 key1000 ... key990 key991 key993 key997
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.126074498567335 $#scanned=-1 $#unscanned=348 num_keys=349 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=174
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=115 num_to_scan=0 $#scanned=173 $#unscanned=174
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key940 guess_value=49
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=44
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=44 (max=40): key639 key640 key701 key811 key851
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key429 key472 key811 key422 key379
Tue Feb  4 17:24:21 2014 rebuilt cache (40/349 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 317: key0 key1 key10 key1000 ... key990 key991 key993 key997
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.138364779874214 $#scanned=-1 $#unscanned=317 num_keys=318 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=159
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=93 num_to_scan=0 $#scanned=158 $#unscanned=158
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key355 guess_value=5337
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=53
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=53 (max=40): key411 key428 key639 key709 key734
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key895 key874 key639 key380 key696
Tue Feb  4 17:24:21 2014 rebuilt cache (40/318 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 286: key0 key1 key10 key1000 ... key988 key990 key991 key997
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.153310104529617 $#scanned=-1 $#unscanned=286 num_keys=287 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=143
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=56 num_to_scan=0 $#scanned=142 $#unscanned=143
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key416 guess_value=5826
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=51
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=51 (max=40): key933 key352 key356 key367 key402
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key453 key839 key359 key434 key605
Tue Feb  4 17:24:21 2014 rebuilt cache (40/287 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 255: key0 key1 key10 key1000 ... key988 key990 key991 key997
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.171875 $#scanned=-1 $#unscanned=255 num_keys=256 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=128
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=14 num_to_scan=0 $#scanned=127 $#unscanned=127
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key864 guess_value=624
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=48
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=48 (max=40): key645 key829 key865 key887 key356
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key650 key42 key645 key273 key752
Tue Feb  4 17:24:21 2014 rebuilt cache (40/256 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 224: key0 key1 key10 key1000 ... key988 key990 key991 key997
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.195555555555556 $#scanned=-1 $#unscanned=224 num_keys=225 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=112
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=3 num_to_scan=0 $#scanned=111 $#unscanned=112
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key894 guess_value=690
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=43
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=43 (max=40): key304 key465 key487 key623 key641
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key642 key456 key698 key400 key304
Tue Feb  4 17:24:21 2014 rebuilt cache (40/225 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 193: key0 key1 key10 key1016 ... key988 key990 key991 key997
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.22680412371134 $#scanned=-1 $#unscanned=193 num_keys=194 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=97
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=93 num_to_scan=0 $#scanned=96 $#unscanned=96
Tue Feb  4 17:24:21 2014 MONTE3 guess_max_key_index=22 guess_max_key=key862 guess_value=746
Tue Feb  4 17:24:21 2014 MONTE4 $#$cr=57
Tue Feb  4 17:24:21 2014 MONTE5 sorting array size $#$cr=57 (max=40): key391 key449 key508 key607 key621
Tue Feb  4 17:24:21 2014 MONTE6 SUCCESS $#$cr=39: key482 key508 key623 key477 key824
Tue Feb  4 17:24:21 2014 rebuilt cache (40/194 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:21 2014 cache_rebuild 162: key0 key1 key10 key1016 ... key988 key990 key991 key997
Tue Feb  4 17:24:21 2014 MONTE1 num_to_scan=800 frac=0.269938650306748 $#scanned=-1 $#unscanned=162 num_keys=163 $#$cr=8
Tue Feb  4 17:24:21 2014 MONTE1a start_scanned=-1 num_to_scan=81
Tue Feb  4 17:24:21 2014 MONTE2 unscanned_start=4 num_to_scan=0 $#scanned=80 $#unscanned=81
Tue Feb  4 17:24:22 2014 MONTE3 guess_max_key_index=22 guess_max_key=key767 guess_value=7748
Tue Feb  4 17:24:22 2014 MONTE4 $#$cr=43
Tue Feb  4 17:24:22 2014 MONTE5 sorting array size $#$cr=43 (max=40): key436 key447 key494 key656 key704
Tue Feb  4 17:24:22 2014 MONTE6 SUCCESS $#$cr=39: key979 key744 key391 key447 key486
Tue Feb  4 17:24:22 2014 rebuilt cache (40/163 keys) in 1 seconds (counting the check, 1 seconds)
Tue Feb  4 17:24:22 2014 cache_rebuild 131: key1 key10 key1016 key1031 ... key988 key990 key991 key997
Tue Feb  4 17:24:22 2014 MONTE1 num_to_scan=800 frac=0.333333333333333 $#scanned=-1 $#unscanned=131 num_keys=132 $#$cr=8
Tue Feb  4 17:24:22 2014 MONTE1a start_scanned=-1 num_to_scan=66
Tue Feb  4 17:24:22 2014 MONTE2 unscanned_start=35 num_to_scan=0 $#scanned=65 $#unscanned=65
Tue Feb  4 17:24:22 2014 MONTE3 guess_max_key_index=22 guess_max_key=key24 guess_value=812
Tue Feb  4 17:24:22 2014 MONTE4 $#$cr=42
Tue Feb  4 17:24:22 2014 MONTE5 sorting array size $#$cr=42 (max=40): key284 key473 key6 key705 key767
Tue Feb  4 17:24:22 2014 MONTE6 SUCCESS $#$cr=39: key475 key717 key665 key494 key922
Tue Feb  4 17:24:22 2014 rebuilt cache (40/132 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:22 2014 cache_rebuild 100: key1 key10 key1016 key11 ... key978 key987 key988 key990
Tue Feb  4 17:24:22 2014 MONTE1 num_to_scan=800 frac=0.435643564356436 $#scanned=-1 $#unscanned=100 num_keys=101 $#$cr=8
Tue Feb  4 17:24:22 2014 MONTE1a start_scanned=-1 num_to_scan=50
Tue Feb  4 17:24:22 2014 MONTE2 unscanned_start=3 num_to_scan=0 $#scanned=49 $#unscanned=50
Tue Feb  4 17:24:22 2014 MONTE3 guess_max_key_index=21 guess_max_key=key882 guess_value=872
Tue Feb  4 17:24:22 2014 MONTE4 $#$cr=49
Tue Feb  4 17:24:22 2014 MONTE5 sorting array size $#$cr=49 (max=40): key454 key502 key652 key748 key753
Tue Feb  4 17:24:22 2014 MONTE6 SUCCESS $#$cr=39: key765 key755 key262 key705 key719
Tue Feb  4 17:24:22 2014 rebuilt cache (40/101 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:22 2014 cache_rebuild 69: key1 key10 key14 key15 ... key972 key978 key988 key990
Tue Feb  4 17:24:22 2014 MONTE1 num_to_scan=800 frac=0.628571428571429 $#scanned=-1 $#unscanned=69 num_keys=70 $#$cr=8
Tue Feb  4 17:24:22 2014 MONTE1a start_scanned=-1 num_to_scan=35
Tue Feb  4 17:24:22 2014 MONTE2 unscanned_start=34 num_to_scan=0 $#scanned=34 $#unscanned=34
Tue Feb  4 17:24:22 2014 MONTE3 guess_max_key_index=21 guess_max_key=key826 guess_value=956
Tue Feb  4 17:24:22 2014 MONTE4 $#$cr=48
Tue Feb  4 17:24:22 2014 MONTE5 sorting array size $#$cr=48 (max=40): key438 key463 key647 key687 key690
Tue Feb  4 17:24:22 2014 MONTE6 SUCCESS $#$cr=39: key271 key753 key748 key268 key757
Tue Feb  4 17:24:22 2014 rebuilt cache (40/70 keys) in 0 seconds (counting the check, 0 seconds)
Tue Feb  4 17:24:22 2014 cache_rebuild 38: key10 key14 key15 key16 ... key930 key972 key988 key990
Tue Feb  4 17:24:22 2014 rebuilt cache (39/39 keys) in 0 seconds (counting the check, 0 seconds)
cachetest.t num_entries=0 db_hashkeys=0
t/cachehash.t .. ok
All tests successful.
Files=1, Tests=14,  7 wallclock secs ( 0.03 usr  0.02 sys +  4.08 cusr  0.02 csys =  4.15 CPU)
Result: PASS

------------------------------
PREREQUISITES
------------------------------

Prerequisite modules loaded:

build_requires:

    Module              Need Have
    ------------------- ---- ----
    ExtUtils::MakeMaker 0    6.88


------------------------------
ENVIRONMENT AND OTHER CONTEXT
------------------------------

Environment variables:

    AUTOMATED_TESTING = 1
    LANG = en_GB.UTF-8
    LC_ADDRESS = en_GB.UTF-8
    LC_COLLATE = en_GB.UTF-8
    LC_CTYPE = en_GB.UTF-8
    LC_IDENTIFICATION = en_GB.UTF-8
    LC_MEASUREMENT = en_GB.UTF-8
    LC_MESSAGES = en_GB.UTF-8
    LC_MONETARY = en_GB.UTF-8
    LC_NAME = en_GB.UTF-8
    LC_NUMERIC = en_GB.UTF-8
    LC_PAPER = en_GB.UTF-8
    LC_TELEPHONE = en_GB.UTF-8
    LC_TIME = en_GB.UTF-8
    PATH = /home/graham/perls/perl5.18.0-thread/bin:/usr/local/sbin:/usr/local/bin:/usr/bin:/usr/bin/core_perl
    PERL5LIB = 
    PERL5OPT = 
    PERL5_CPANPLUS_IS_RUNNING = 29284
    PERL5_CPAN_IS_RUNNING = 29284
    PERL5_CPAN_IS_RUNNING_IN_RECURSION = 494,29284
    PERL_CR_SMOKER_CURRENT = Tie-CacheHash-0.50
    PERL_EXTUTILS_AUTOINSTALL = --defaultdeps
    PERL_MM_USE_DEFAULT = 1
    SHELL = /bin/bash
    TERM = xterm

Perl special variables (and OS-specific diagnostics, for MSWin32):

    $^X = /home/graham/perls/perl5.18.0-thread/bin/perl
    $UID/$EUID = 1000 / 1000
    $GID = 100 10 100
    $EGID = 100 10 100

Perl module toolchain versions installed:

    Module              Have    
    ------------------- --------
    CPAN                1.9402  
    CPAN::Meta          2.133380
    Cwd                 3.40    
    ExtUtils::CBuilder  0.280212
    ExtUtils::Command   1.18    
    ExtUtils::Install   1.59    
    ExtUtils::MakeMaker 6.88    
    ExtUtils::Manifest  1.63    
    ExtUtils::ParseXS   3.22    
    File::Spec          3.40    
    JSON                2.90    
    JSON::PP            2.27203 
    Module::Build       0.4204  
    Module::Signature   n/a     
    Parse::CPAN::Meta   1.4409  
    Test::Harness       3.30    
    Test::More          1.001002
    YAML                0.88    
    YAML::Syck          1.27    
    version             0.9908  


--

Summary of my perl5 (revision 5 version 18 subversion 0) configuration:
   
  Platform:
    osname=linux, osvers=3.11.6-1-arch, archname=x86_64-linux-thread-multi
    uname='linux arch002 3.11.6-1-arch #1 smp preempt fri oct 18 23:22:36 cest 2013 x86_64 gnulinux '
    config_args=''
    hint=recommended, useposix=true, d_sigaction=define
    useithreads=define, usemultiplicity=define
    useperlio=define, d_sfio=undef, uselargefiles=define, usesocks=undef
    use64bitint=define, use64bitall=define, uselongdouble=undef
    usemymalloc=n, bincompat5005=undef
  Compiler:
    cc='cc', ccflags ='-D_REENTRANT -D_GNU_SOURCE -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64',
    optimize='-O2',
    cppflags='-D_REENTRANT -D_GNU_SOURCE -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include'
    ccversion='', gccversion='4.8.2', gccosandvers=''
    intsize=4, longsize=8, ptrsize=8, doublesize=8, byteorder=12345678
    d_longlong=define, longlongsize=8, d_longdbl=define, longdblsize=16
    ivtype='long', ivsize=8, nvtype='double', nvsize=8, Off_t='off_t', lseeksize=8
    alignbytes=8, prototype=define
  Linker and Libraries:
    ld='cc', ldflags =' -fstack-protector -L/usr/local/lib'
    libpth=/usr/local/lib /lib/../lib /usr/lib/../lib /lib /usr/lib /lib64 /usr/lib64
    libs=-lnsl -lgdbm -ldb -ldl -lm -lcrypt -lutil -lpthread -lc -lgdbm_compat
    perllibs=-lnsl -ldl -lm -lcrypt -lutil -lpthread -lc
    libc=/lib/libc-2.18.so, so=so, useshrplib=false, libperl=libperl.a
    gnulibc_version='2.18'
  Dynamic Linking:
    dlsrc=dl_dlopen.xs, dlext=so, d_dlsymun=undef, ccdlflags='-Wl,-E'
    cccdlflags='-fPIC', lddlflags='-shared -O2 -L/usr/local/lib -fstack-protector'


Characteristics of this binary (from libperl): 
  Compile-time options: HAS_TIMES MULTIPLICITY PERLIO_LAYERS
                        PERL_DONT_CREATE_GVSV
                        PERL_HASH_FUNC_ONE_AT_A_TIME_HARD
                        PERL_IMPLICIT_CONTEXT PERL_MALLOC_WRAP
                        PERL_PRESERVE_IVUV PERL_SAWAMPERSAND USE_64_BIT_ALL
                        USE_64_BIT_INT USE_ITHREADS USE_LARGE_FILES
                        USE_LOCALE USE_LOCALE_COLLATE USE_LOCALE_CTYPE
                        USE_LOCALE_NUMERIC USE_PERLIO USE_PERL_ATOF
                        USE_REENTRANT_API
  Built under linux
  Compiled at Dec  6 2013 08:54:47
  %ENV:
    PERL5LIB=""
    PERL5OPT=""
    PERL5_CPANPLUS_IS_RUNNING="29284"
    PERL5_CPAN_IS_RUNNING="29284"
    PERL5_CPAN_IS_RUNNING_IN_RECURSION="494,29284"
    PERL_CR_SMOKER_CURRENT="Tie-CacheHash-0.50"
    PERL_EXTUTILS_AUTOINSTALL="--defaultdeps"
    PERL_MM_USE_DEFAULT="1"
  @INC:
    /home/graham/perls/perl5.18.0-thread/lib/site_perl/5.18.0/x86_64-linux-thread-multi
    /home/graham/perls/perl5.18.0-thread/lib/site_perl/5.18.0
    /home/graham/perls/perl5.18.0-thread/lib/5.18.0/x86_64-linux-thread-multi
    /home/graham/perls/perl5.18.0-thread/lib/5.18.0
    .