__step=000002
__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_abtree_lf.debra -nwork 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000002.txt
__path_data=data/data000002.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=206791.59234704
binary=./brown_ext_abtree_lf.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=brown_ext_abtree_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=0.5
DEL_FRAC=0.5
RQ=0
RQSIZE=0
MAXKEY=20000000
PREFILL_THREADS=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
RQ_THREADS=0
distribution=0
INS_DEL_FRAC=0.5 0.5
PREFILL_TYPE=PREFILL_HYBRID
PREFILL_HYBRID_MIN_MS=1000
PREFILL_HYBRID_MAX_MS=5000
size_node=208
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.019s
prefilling_size=8286152 prefilling_elapsed_millis=1s
prefilling_size=8545262 prefilling_elapsed_millis=1.101s
prefilling_size=8763878 prefilling_elapsed_millis=1.201s
prefilling_size=8947165 prefilling_elapsed_millis=1.301s
prefilling_size=9101669 prefilling_elapsed_millis=1.401s
prefilling_size=9232383 prefilling_elapsed_millis=1.501s
prefilling_size=9342264 prefilling_elapsed_millis=1.601s
prefilling_size=9435814 prefilling_elapsed_millis=1.701s
prefilling_size=9517872 prefilling_elapsed_millis=1.801s
prefilling_size=9584315 prefilling_elapsed_millis=1.902s
prefilling_size=9641716 prefilling_elapsed_millis=2.002s
prefilling_size=9691659 prefilling_elapsed_millis=2.102s
prefilling_size=9736001 prefilling_elapsed_millis=2.202s
prefilling_size=9772649 prefilling_elapsed_millis=2.302s
prefilling_size=9802717 prefilling_elapsed_millis=2.402s
finished prefilling to size 9802757 for expected size 10000000 keysum=98027386781494, performing 92338976 updates; total_prefilling_elapsed_ms=2503 ms)
pref_size=9802757
pref_millis=2503
prefill_elapsed_ms=2521
N9abtree_ns4NodeILi11ExEE_object_size=208
N9abtree_ns4NodeILi11ExEE_allocated_count=0
N9abtree_ns4NodeILi11ExEE_allocated_size=0MB
N9abtree_ns4NodeILi11ExEE_get_from_pool=0
N9abtree_ns4NodeILi11ExEE_deallocated=0
N9abtree_ns4NodeILi11ExEE_limbo_count=0
N9abtree_ns4NodeILi11ExEE_limbo_details=0 0 0
global_epoch_counter=2884
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=206793.613954711
REALTIME_END_PERF_FORMAT=206803.614020588
###############################################################################
################################## TIME IS UP #################################
###############################################################################
joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################
10s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (256 threads)...
partitioned into 3528 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.153s
tree_stats_numInternalsAtDepth=1 1 8 61 480 3528 26508 207503 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1636101
tree_stats_numNodesAtDepth=1 1 8 61 480 3528 26508 207503 1636101
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9847263
tree_stats_avgDegreeAtDepth=1 8 7.625 7.86885 7.35 7.51361 7.82794 7.88471 6.01874
tree_stats_height=9
tree_stats_numInternals=238090
tree_stats_numLeaves=1636101
tree_stats_numNodes=1874191
tree_stats_numKeys=9847263
tree_stats_avgDegreeInternal=7.87178
tree_stats_avgDegreeLeaves=6.01874
tree_stats_avgDegree=6.25414
tree_stats_avgKeyDepth=8
tree_stats_bytesAtDepth=208 208 1664 12688 99840 733824 5513664 43160624 340309008
tree_stats_sizeInBytes=389831728
N9abtree_ns4NodeILi11ExEE_object_size=208
N9abtree_ns4NodeILi11ExEE_allocated_count=0
N9abtree_ns4NodeILi11ExEE_allocated_size=0MB
N9abtree_ns4NodeILi11ExEE_get_from_pool=0
N9abtree_ns4NodeILi11ExEE_deallocated=0
N9abtree_ns4NodeILi11ExEE_limbo_count=0
N9abtree_ns4NodeILi11ExEE_limbo_details=0 0 0
global_epoch_counter=20922
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:101961 2:282420 4:207616 8:11456 16:13 32:121 64:2
[2^00, 2^01]: 101961
(2^01, 2^02]: 282420
(2^02, 2^03]: 207616
(2^03, 2^04]: 11456
(2^04, 2^05]: 13
(2^05, 2^06]: 121
(2^06, 2^07]: 2
sum_limbo_reclamation_event_size_total=1908616
count_limbo_reclamation_event_size_by_thread=9567 9484 9580 9541 9544 9520 9545 9546 9424 9521 9490 9495 9111 9101 9111 9222 9516 9553 9528 9541 9505 9506 9455 9511 9062 9142 9083 9140 9532 9538 9481 9527 9464 9542 9519 9531 9491 9437 9317 9493 9428 9526 9420 9574 9566 9543 9476 9532 9498 9487 9444 9506 8991 9175 8899 9098 9452 9565 9533 9537 9524 9549 9473 9577
count_limbo_reclamation_event_size_total=603589
sum_limbo_reclamation_event_count_total=1154432
first_thread_announced_epoch_by_thread=41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844 41844
sum_num_inserts_by_thread=56745 52633 57112 54899 55573 55232 54884 55885 51313 54762 52909 53888 43819 44543 44319 45738 55166 55082 54580 55109 54463 53783 52918 53383 43675 44079 42967 44136 54679 55176 54273 55400 52334 55908 54217 55027 52641 53449 50104 53869 52438 53096 52563 56015 55262 56076 54104 55403 53694 54016 51121 53454 41559 44366 41249 43495 52645 55513 54497 55232 54459 55045 52628 56586
sum_num_inserts_total=3345188
sum_num_deletes_by_thread=57191 52652 57122 54573 55366 55065 55152 55854 51497 54947 53054 53604 43770 45010 44162 45908 54851 55219 54785 54992 54360 54147 53173 53957 43378 43764 43027 44317 54125 55711 54325 55475 51956 55690 54046 54452 52407 52905 50097 52966 52087 53437 51934 56216 54777 55661 54362 54863 53946 54517 50958 53791 41680 44309 40940 43932 52913 54973 54869 55037 54838 55664 52352 56053
sum_num_deletes_total=3343161
sum_num_searches_by_thread=11240695 10431112 11268153 10834296 10995702 10963416 10928999 11075865 10245576 10848058 10487748 10585745 8716291 8901274 8759604 9024342 10859001 10907598 10857971 10886461 10794918 10677666 10474344 10658825 8659378 8686411 8538027 8756279 10808015 11012716 10707550 11004201 10339382 11075075 10722263 10876848 10411057 10444545 9934430 10587905 10303001 10542367 10304903 11185560 10856788 11045685 10719919 10851440 10649125 10726303 10088318 10623568 8272439 8752492 8166445 8631482 10409878 10956509 10821757 10907817 10851108 10968013 10372754 11213034
sum_num_searches_total=662208447
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=11354631 10536397 11382387 10943768 11106641 11073713 11039035 11187604 10348386 10957767 10593711 10693237 8803880 8990827 8848085 9115988 10969018 11017899 10967336 10996562 10903741 10785596 10580435 10766165 8746431 8774254 8624021 8844732 10916819 11123603 10816148 11115076 10443672 11186673 10830526 10986327 10516105 10550899 10034631 10694740 10407526 10648900 10409400 11297791 10966827 11157422 10828385 10961706 10756765 10834836 10190397 10730813 8355678 8841167 8248634 8718909 10515436 11066995 10931123 11018086 10960405 11078722 10477734 11325673
average_num_operations_total=10451512
stdev_num_operations_total=110395
sum_num_operations_total=668896796
min_num_operations_total=8248634
max_num_operations_total=11382387
first_time_thread_terminate_by_thread=10000068 10000068 10000068 10000068 10000068 10000068 10000068 10000069 10000067 10000067 10000067 10000067 10000067 10000066 10000066 10000067 10000068 10000068 10000068 10000068 10000065 10000065 10000065 10000065 10000066 10000066 10000066 10000066 10000067 10000067 10000067 10000067 10000066 10000066 10000066 10000066 10000067 10000068 10000067 10000068 10000067 10000067 10000067 10000067 10000068 10000068 10000068 10000068 10000065 10000065 10000067 10000065 10000069 10000069 10000069 10000069 10000065 10000065 10000065 10000065 10000069 10000069 10000069 10000069
min_time_thread_terminate_total=10000065
max_time_thread_terminate_total=10000069
first_time_thread_start_by_thread=3 4 4 4 7 7 7 7 4 4 4 4 6 6 6 6 7 7 7 7 6 6 6 6 7 7 7 7 6 6 6 6 5 5 5 5 8 8 8 7 3 3 3 3 4 4 4 4 5 5 5 5 4 4 4 4 5 5 5 5 3 3 3 3
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=
threads_final_keysum=98474550054372
threads_final_size=9847263
final_keysum=98474550054372
final_size=9847263
validate_result=success
Validation OK.
Structural validation OK.
total_find=662208447
total_rq=0
total_inserts=3345188
total_deletes=3343161
total_updates=6688349
total_queries=662208447
total_ops=668896796
find_throughput=66220844
rq_throughput=0
update_throughput=668834
query_throughput=66220844
total_throughput=66889679
total find : 662208447
total rq : 0
total inserts : 3345188
total deletes : 3343161
total updates : 6688349
total queries : 662208447
total ops : 668896796
find throughput : 66220844
rq throughput : 0
update throughput : 668834
query throughput : 66220844
total throughput : 66889679
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=741841510018554
total_execution_walltime=12.795s
REALTIME_MAIN_END_PERF_FORMAT=206803.855189957
[time_cmd_output] time_elapsed_sec=12.92, faults_major=0, faults_minor=180072, mem_maxresident_kb=721072, user_cputime=833.48, sys_cputime=5.34, percent_cpu=6487%
timeout=false
algorithm=brown_ext_abtree_lf.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2