__step=000420
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 60 numactl -i all /usr/bin/time -f "[time_cmd_output] time_elapsed_sec=%e, faults_major=%F, faults_minor=%R, mem_maxresident_kb=%M, user_cputime=%U, sys_cputime=%S, percent_cpu=%P" ./brown_ext_chromatic_lf.debra -nwork 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000420.txt
__path_data=data/data000420.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983936.332137612
binary=./brown_ext_chromatic_lf.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_ext_chromatic_lf
FIND_FUNC=FIND_FUNC
INSERT_FUNC=insertIfAbsent
ERASE_FUNC=ERASE_FUNC
RQ_FUNC=RQ_FUNC
RECLAIM=reclaimer_debra
ALLOC=allocator_new
POOL=pool_none
MAX_THREADS_POW2=512
CPU_FREQ_GHZ=2.1
MILLIS_TO_RUN=10000
INS_FRAC=5
DEL_FRAC=5
RQ=0
RQSIZE=0
MAXKEY=20000000
PREFILL_THREADS=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
RQ_THREADS=0
distribution=0
INS_DEL_FRAC=5.0 5.0
PREFILL_TYPE=PREFILL_HYBRID
PREFILL_HYBRID_MIN_MS=1000
PREFILL_HYBRID_MAX_MS=5000
sizes: node=56 descriptor=128
ACTUAL_THREAD_BINDINGS=0,1,2,3,4,5,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,23,96,97,98,99,100,101,102,103,104,105,106,107,108,109,110,111,112,113,114,115,116,117,118,119,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.036s
prefilling_size=6217796 prefilling_elapsed_millis=1.001s
prefilling_size=6519916 prefilling_elapsed_millis=1.101s
prefilling_size=6796542 prefilling_elapsed_millis=1.201s
prefilling_size=7047899 prefilling_elapsed_millis=1.302s
prefilling_size=7278449 prefilling_elapsed_millis=1.402s
prefilling_size=7487377 prefilling_elapsed_millis=1.502s
prefilling_size=7678160 prefilling_elapsed_millis=1.602s
prefilling_size=7847447 prefilling_elapsed_millis=1.702s
prefilling_size=8008921 prefilling_elapsed_millis=1.803s
prefilling_size=8155194 prefilling_elapsed_millis=1.903s
prefilling_size=8290673 prefilling_elapsed_millis=2.003s
prefilling_size=8418654 prefilling_elapsed_millis=2.103s
prefilling_size=8534042 prefilling_elapsed_millis=2.203s
prefilling_size=8641758 prefilling_elapsed_millis=2.304s
prefilling_size=8738456 prefilling_elapsed_millis=2.404s
prefilling_size=8828754 prefilling_elapsed_millis=2.504s
prefilling_size=8912032 prefilling_elapsed_millis=2.604s
prefilling_size=8989296 prefilling_elapsed_millis=2.704s
prefilling_size=9059778 prefilling_elapsed_millis=2.805s
prefilling_size=9126705 prefilling_elapsed_millis=2.905s
prefilling_size=9187433 prefilling_elapsed_millis=3.005s
prefilling_size=9243903 prefilling_elapsed_millis=3.105s
prefilling_size=9297164 prefilling_elapsed_millis=3.205s
prefilling_size=9346458 prefilling_elapsed_millis=3.306s
prefilling_size=9392361 prefilling_elapsed_millis=3.406s
prefilling_size=9435351 prefilling_elapsed_millis=3.506s
prefilling_size=9472881 prefilling_elapsed_millis=3.606s
prefilling_size=9508350 prefilling_elapsed_millis=3.706s
prefilling_size=9541397 prefilling_elapsed_millis=3.807s
prefilling_size=9571292 prefilling_elapsed_millis=3.907s
prefilling_size=9600826 prefilling_elapsed_millis=4.007s
prefilling_size=9628579 prefilling_elapsed_millis=4.107s
prefilling_size=9652703 prefilling_elapsed_millis=4.207s
prefilling_size=9674191 prefilling_elapsed_millis=4.308s
prefilling_size=9696800 prefilling_elapsed_millis=4.408s
prefilling_size=9717068 prefilling_elapsed_millis=4.508s
prefilling_size=9736535 prefilling_elapsed_millis=4.608s
prefilling_size=9754718 prefilling_elapsed_millis=4.708s
prefilling_size=9771669 prefilling_elapsed_millis=4.809s
prefilling_size=9786769 prefilling_elapsed_millis=4.909s
prefilling_size=9799752 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 200275 keys with 96 threads...
tid=0 thread_prefill_amount=116648 percent_done_estimate=5591.4 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0313429s
prefilling_size=10000000 prefilling_elapsed_millis=0.031s
finished prefilling to size 10000000 for expected size 10000000 keysum=99987224934420, performing 81077869 updates; total_prefilling_elapsed_ms=31 ms)
pref_size=10000000
pref_millis=31
prefill_elapsed_ms=31
4NodeIxPvE_object_size=56
4NodeIxPvE_allocated_count=0
4NodeIxPvE_allocated_size=0MB
4NodeIxPvE_get_from_pool=0
4NodeIxPvE_deallocated=0
4NodeIxPvE_limbo_count=92699
4NodeIxPvE_limbo_details=35530 26474 30695 

global_epoch_counter=1630

9SCXRecordIxPvE_object_size=128
9SCXRecordIxPvE_allocated_count=0
9SCXRecordIxPvE_allocated_size=0MB
9SCXRecordIxPvE_get_from_pool=0
9SCXRecordIxPvE_deallocated=0
9SCXRecordIxPvE_limbo_count=46071
9SCXRecordIxPvE_limbo_details=17648 13077 15346 

global_epoch_counter=0

main thread: starting timer...

###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################

REALTIME_START_PERF_FORMAT=6983941.688158067
REALTIME_END_PERF_FORMAT=6983951.688243409

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10.1s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (96 threads)...
partitioned into 512 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.047s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65529 130766 258111 489982 853748 1300954 1668956 1766681 1515979 1034852 548819 221620 66166 13927 1989 176 14 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 7 292 3421 26240 126216 406542 932952 1571231 2017383 1997106 1520885 876018 377074 118405 25865 3802 338 27 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131058 261532 516222 979964 1707496 2601908 3337912 3533362 3031958 2069704 1097638 443240 132332 27854 3978 352 28 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 7 292 3421 26240 126216 406542 932952 1571231 2017383 1997106 1520885 876018 377074 118405 25865 3802 338 27 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99989 1.99777 1.98692 1.94917 1.8712 1.76191 1.64144 1.52928 1.42905 1.34131 1.26517 1.20191 1.14928 1.10524 1.07141 1.04424 1.03977 1.03571 1

tree_stats_height=35
tree_stats_numInternals=10003805
tree_stats_numLeaves=10003806
tree_stats_numNodes=20007611
tree_stats_numKeys=10003806

tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=24.4635

tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7339248 14645792 28908432 54877984 95619776 145706848 186923072 197868272 169789648 115903424 61467728 24821440 7410592 1559824 222768 19712 1568 112
tree_stats_sizeInBytes=1120426216

4NodeIxPvE_object_size=56
4NodeIxPvE_allocated_count=0
4NodeIxPvE_allocated_size=0MB
4NodeIxPvE_get_from_pool=0
4NodeIxPvE_deallocated=0
4NodeIxPvE_limbo_count=0
4NodeIxPvE_limbo_details=0 0 0 

global_epoch_counter=6246

9SCXRecordIxPvE_object_size=128
9SCXRecordIxPvE_allocated_count=0
9SCXRecordIxPvE_allocated_size=0MB
9SCXRecordIxPvE_get_from_pool=0
9SCXRecordIxPvE_deallocated=0
9SCXRecordIxPvE_limbo_count=0
9SCXRecordIxPvE_limbo_details=0 0 0 

global_epoch_counter=0


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:12 2:12 4:18 8:51 16:14629 32:424166 64:440484 128:6568 256:406
    [2^00, 2^01]: 12
    (2^01, 2^02]: 12
    (2^02, 2^03]: 18
    (2^03, 2^04]: 51
    (2^04, 2^05]: 14629
    (2^05, 2^06]: 424166
    (2^06, 2^07]: 440484
    (2^07, 2^08]: 6568
    (2^08, 2^09]: 406
sum_limbo_reclamation_event_size_total=62037171
count_limbo_reclamation_event_size_by_thread=9232 9234 9234 9232 9232 9234 9234 9234 9232 9234 9232 9234 9232 9232 9232 9232 9232 9233 9232 9234 9234 9234 9232 9234 9232 9234 9234 9232 9232 9234 9234 9232 9232 9232 9232 9234 9232 9232 9232 9232 9232 9232 9234 9232 9234 9234 9234 9234 9232 9234 9232 9232 9232 9232 9232 9232 9232 9234 9234 9232 9232 9234 9233 9232 9232 9234 9232 9232 9232 9232 9234 9234 9234 9234 9232 9232 9233 9232 9232 9232 9232 9234 9234 9233 9232 9233 9232 9232 9234 9232 9232 9232 9232 9232 9234 9233
count_limbo_reclamation_event_size_total=886346
sum_limbo_reclamation_event_count_total=886442
first_thread_announced_epoch_by_thread=12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492 12492
sum_num_inserts_by_thread=212178 213417 215615 215076 212990 212359 212151 214366 216232 217205 213261 212852 213481 213410 215548 215359 215408 213024 211160 213256 215237 215560 212612 211041 210651 212800 215673 215273 214639 211472 211518 214132 216067 216182 214222 212724 212945 213800 216314 215172 214372 212676 211466 212466 214759 215041 211599 210919 212912 213506 214779 214836 214286 212492 212725 214271 216132 217257 215297 212850 213715 215407 216041 216083 214842 212756 211392 211691 215292 216287 213734 211998 212435 213857 215360 215432 213754 212764 212378 214343 216181 215750 214437 214041 213757 215485 215589 215283 214070 211675 210432 212993 215253 214818 214076 212174
sum_num_inserts_total=20536598
sum_num_deletes_by_thread=211744 213683 215664 214878 214001 212619 213059 213629 216947 216750 213731 212501 213443 214256 216314 215642 214761 212422 210386 212913 214582 214695 211965 210829 211545 212903 215367 214910 214079 211044 211865 213236 216408 215759 213981 212234 213483 214214 216386 215401 214731 212382 211497 212618 214282 215105 212950 210655 212297 213467 215488 215806 213505 211870 212515 214743 216199 215728 214541 213552 213839 214239 217001 216727 214024 211786 211073 212808 214824 216062 214150 211924 212641 213972 215243 215273 214579 212100 213109 213428 216065 216608 214290 213008 213841 214281 215940 215907 214260 212184 211036 213032 215385 215561 213469 212319
sum_num_deletes_total=20534148
sum_num_searches_by_thread=3816280 3836279 3870838 3871883 3847377 3811027 3833752 3851633 3898358 3899651 3846435 3827210 3842471 3856343 3890629 3889167 3864183 3833910 3807884 3827489 3861386 3876053 3825154 3795281 3799550 3836802 3873771 3873266 3848159 3803370 3823497 3847461 3886251 3889835 3850772 3818811 3846760 3859660 3888125 3888272 3856926 3828625 3803856 3833169 3851708 3882253 3830109 3800433 3821154 3842123 3873462 3869539 3842611 3818768 3828542 3846414 3888183 3895283 3861293 3830003 3836768 3866534 3892255 3890745 3859650 3814305 3796620 3825286 3875149 3879085 3847287 3820633 3829622 3849717 3880889 3878621 3847573 3814451 3821677 3849833 3892186 3897138 3866258 3833484 3841870 3859110 3882775 3889377 3856038 3813551 3799080 3825219 3877180 3884048 3849590 3820632
sum_num_searches_total=369583725
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4240202 4263379 4302117 4301837 4274368 4236005 4258962 4279628 4331537 4333606 4273427 4252563 4269395 4284009 4322491 4320168 4294352 4259356 4229430 4253658 4291205 4306308 4249731 4217151 4221746 4262505 4304811 4303449 4276877 4225886 4246880 4274829 4318726 4321776 4278975 4243769 4273188 4287674 4320825 4318845 4286029 4253683 4226819 4258253 4280749 4312399 4254658 4222007 4246363 4269096 4303729 4300181 4270402 4243130 4253782 4275428 4320514 4328268 4291131 4256405 4264322 4296180 4325297 4323555 4288516 4238847 4219085 4249785 4305265 4311434 4275171 4244555 4254698 4277546 4311492 4309326 4275906 4239315 4247164 4277604 4324432 4329496 4294985 4260533 4269468 4288876 4314304 4320567 4284368 4237410 4220548 4251244 4307818 4314427 4277135 4245125
average_num_operations_total=4277650
stdev_num_operations_total=3252
sum_num_operations_total=410654471
min_num_operations_total=4217151
max_num_operations_total=4333606
first_time_thread_terminate_by_thread=10000159 10000085 10000085 10000088 10000085 10000087 10000085 10000087 10000085 10000085 10000087 10000088 10000085 10000088 10000088 10000086 10000087 10000086 10000087 10000085 10000088 10000087 10000088 10000087 10000084 10000085 10000085 10000088 10000085 10000087 10000085 10000085 10000085 10000085 10000087 10000085 10000087 10000088 10000085 10000088 10000087 10000085 10000085 10000086 10000086 10000085 10000085 10000088 10000087 10000085 10000086 10000086 10000085 10000088 10000085 10000087 10000085 10000088 10000087 10000085 10000087 10000085 10000085 10000087 10000088 10000086 10000087 10000085 10000085 10000086 10000085 10000087 10000087 10000086 10000086 10000085 10000085 10000085 10000085 10000085 10000085 10000088 10000087 10000085 10000087 10000085 10000085 10000087 10000088 10000086 10000087 10000086 10000088 10000086 10000086 10000087
min_time_thread_terminate_total=10000084
max_time_thread_terminate_total=10000159
first_time_thread_start_by_thread=10 5 5 6 6 5 5 5 6 6 6 6 6 6 6 6 6 6 6 6 5 5 6 6 5 5 5 6 6 5 5 5 6 6 6 6 6 6 6 6 6 6 6 6 5 5 6 6 7 6 6 7 6 7 7 6 6 6 6 7 7 7 6 6 6 6 6 7 7 6 6 6 7 6 6 7 6 7 7 6 6 6 6 7 7 7 6 6 6 6 6 7 7 6 6 6
min_time_thread_start_total=5
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=100058240276046
threads_final_size=10003806
final_keysum=100058240276046
final_size=10003806
validate_result=success
Validation OK.
Structural validation OK.

total_find=369583725
total_rq=0
total_inserts=20536598
total_deletes=20534148
total_updates=41070746
total_queries=369583725
total_ops=410654471
find_throughput=36958372
rq_throughput=0
update_throughput=4107074
query_throughput=36958372
total_throughput=41065447


total find                    : 369583725
total rq                      : 0
total inserts                 : 20536598
total deletes                 : 20534148
total updates                 : 41070746
total queries                 : 369583725
total ops                     : 410654471
find throughput               : 36958372
rq throughput                 : 0
update throughput             : 4107074
query throughput              : 36958372
total throughput              : 41065447

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=36.1317
PAPI_L3_TCM=13.6171
PAPI_TOT_CYC=6505.55
PAPI_TOT_INS=445.328
garbage=1848225226022879
total_execution_walltime=15.58s
REALTIME_MAIN_END_PERF_FORMAT=6983951.913123492
[time_cmd_output] time_elapsed_sec=15.92, faults_major=0, faults_minor=727908, mem_maxresident_kb=2909940, user_cputime=1448.91, sys_cputime=10.36, percent_cpu=9165%
timeout=false
algorithm=brown_ext_chromatic_lf.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2