__step=000507
__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" ./drachsler_pext_bst_lock.debra -nwork 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000507.txt
__path_data=data/data000507.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6985004.915622926
binary=./drachsler_pext_bst_lock.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=drachsler_pext_bst_lock
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=2000000
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=192
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.023s
prefilling_size=999710 prefilling_elapsed_millis=1.001s
finished prefilling to size 999691 for expected size 1000000 keysum=998970737706, performing 54404982 updates; total_prefilling_elapsed_ms=1103 ms)
pref_size=999691
pref_millis=1103
prefill_elapsed_ms=1125
6node_tIxPvE_object_size=192
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=653

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6985006.219801516
REALTIME_END_PERF_FORMAT=6985016.219950642

###############################################################################
################################## 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 (192 threads)...
partitioned into 927 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.034s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 249 479 890 1616 2859 4879 7909 12228 18138 25094 32848 40210 46686 51457 53847 53945 51421 47783 42363 36424 30174 24404 19311 14747 10981 7977 5630 3849 2431 1456 861 484 265 144 76 22 14 4 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 2 11 37 96 196 448 1025 1960 3463 6058 9365 13646 18081 22328 26123 28442 29735 28823 27987 25298 22294 18808 15466 12486 9625 7283 5402 3813 2747 1710 1068 652 364 211 105 71 12 11 5
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 127 251 490 927 1712 3055 5327 8934 14188 21601 31152 42213 53856 64767 73785 79970 82387 81156 76606 70350 61722 52468 43212 34777 27233 20606 15260 11032 7662 5178 3166 1929 1136 629 355 181 93 26 15 5
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 127 251 490 927 1712 3055 5327 8934 14188 21601 31152 42213 53856 64767 73785 79970 82387 81156 76606 70350 61722 52468 43212 34777 27233 20606 15260 11032 7662 5178 3166 1929 1136 629 355 181 93 26 15 5
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.98438 1.97638 1.96016 1.91429 1.88673 1.84054 1.80786 1.76122 1.70282 1.66063 1.60247 1.54953 1.49767 1.45598 1.41841 1.38643 1.35688 1.33028 1.31033 1.29459 1.27518 1.25994 1.24849 1.24005 1.22779 1.21514 1.20766 1.2002 1.18419 1.17345 1.14195 1.1494 1.14256 1.12764 1.14308 1.10423 1.09392 1.04301 1.03846 1.06667 1

tree_stats_height=47
tree_stats_numInternals=654409
tree_stats_numLeaves=345257
tree_stats_numNodes=999666
tree_stats_numKeys=999666

tree_stats_avgDegreeInternal=1.52759
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34537
tree_stats_avgKeyDepth=23.8942

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24384 48192 94080 177984 328704 586560 1022784 1715328 2724096 4147392 5981184 8104896 10340352 12435264 14166720 15354240 15818304 15581952 14708352 13507200 11850624 10073856 8296704 6677184 5228736 3956352 2929920 2118144 1471104 994176 607872 370368 218112 120768 68160 34752 17856 4992 2880 960
tree_stats_sizeInBytes=191935872

6node_tIxPvE_object_size=192
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=11009


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:1 2:2 4:58 8:43391 16:876258 32:39955 64:62 128:81
    [2^00, 2^01]: 1
    (2^01, 2^02]: 2
    (2^02, 2^03]: 58
    (2^03, 2^04]: 43391
    (2^04, 2^05]: 876258
    (2^05, 2^06]: 39955
    (2^06, 2^07]: 62
    (2^07, 2^08]: 81
sum_limbo_reclamation_event_size_total=22204194
count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998
count_limbo_reclamation_event_size_total=959808
sum_limbo_reclamation_event_count_total=994176
first_thread_announced_epoch_by_thread=22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018 22018
sum_num_inserts_by_thread=472754 476029 481446 483629 480996 475343 478749 481010 485980 486513 480489 476908 477675 480108 483321 482574 481676 478867 472180 476197 482434 484532 480201 474291 474796 478248 482586 484464 479240 474091 478425 480104 485880 486351 479801 476900 477739 479795 483982 483150 481679 478068 472683 474508 480724 484768 476251 473416 472689 477592 482371 479891 477261 477858 476019 477989 486709 485412 482201 477137 480554 481872 483790 484534 479037 471291 471934 474273 480288 479928 476688 473746 474291 475755 480747 479571 478041 474993 477759 479274 485320 485773 480852 476317 476108 482493 482699 484679 477864 475726 471209 476298 482920 482630 480889 474833
sum_num_inserts_total=46009656
sum_num_deletes_by_thread=473381 476822 479584 481795 481354 473940 476874 478742 485394 485652 480712 474437 477750 481172 483370 484187 479998 477669 473956 476490 482869 483489 479707 474100 475611 478088 482219 483310 480636 473542 477685 480395 488236 485647 479493 478680 476881 480166 485174 482691 481071 477202 472769 474299 480397 483693 476601 472935 473729 478692 483245 480472 476519 477112 477513 478234 486423 483069 481326 475368 477970 482657 484431 484329 478992 471528 473734 475153 481784 481393 475801 473595 473966 477370 480439 478862 478031 474833 477193 479917 485038 485121 481265 476161 476908 481985 483302 485585 480458 474930 472097 477480 484500 482689 480713 476177
sum_num_deletes_total=46008954
sum_num_searches_by_thread=8513707 8585685 8657667 8666267 8656520 8542935 8601807 8648150 8736372 8752275 8639196 8563174 8607372 8635401 8694589 8701498 8660901 8599802 8526094 8572879 8677410 8703372 8633751 8541443 8556100 8618648 8679550 8705959 8639828 8519774 8604002 8653268 8747066 8732921 8632869 8586229 8588627 8643952 8722230 8703316 8665628 8582369 8517107 8535462 8649919 8709604 8587897 8520875 8530695 8605702 8692759 8646201 8582488 8590948 8597108 8614046 8763044 8725299 8681584 8574773 8625125 8673816 8695048 8731915 8601568 8497670 8500824 8551391 8657044 8642280 8571383 8530958 8517875 8580696 8639967 8629862 8590623 8551183 8589317 8638571 8723622 8733623 8650282 8569240 8587997 8666599 8715074 8728391 8618868 8550244 8494080 8585403 8714483 8686958 8644720 8564742
sum_num_searches_total=828077556
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=9459842 9538536 9618697 9631691 9618870 9492218 9557430 9607902 9707746 9724440 9600397 9514519 9562797 9596681 9661280 9668259 9622575 9556338 9472230 9525566 9642713 9671393 9593659 9489834 9506507 9574984 9644355 9673733 9599704 9467407 9560112 9613767 9721182 9704919 9592163 9541809 9543247 9603913 9691386 9669157 9628378 9537639 9462559 9484269 9611040 9678065 9540749 9467226 9477113 9561986 9658375 9606564 9536268 9545918 9550640 9570269 9736176 9693780 9645111 9527278 9583649 9638345 9663269 9700778 9559597 9440489 9446492 9500817 9619116 9603601 9523872 9478299 9466132 9533821 9601153 9588295 9546695 9501009 9544269 9597762 9693980 9704517 9612399 9521718 9541013 9631077 9681075 9698655 9577190 9500900 9437386 9539181 9681903 9652277 9606322 9515752
average_num_operations_total=9584335
stdev_num_operations_total=7827
sum_num_operations_total=920096166
min_num_operations_total=9437386
max_num_operations_total=9736176
first_time_thread_terminate_by_thread=10000150 10000151 10000149 10000149 10000149 10000148 10000150 10000148 10000151 10000151 10000150 10000151 10000149 10000150 10000149 10000149 10000149 10000149 10000149 10000151 10000149 10000149 10000149 10000148 10000150 10000151 10000150 10000149 10000149 10000148 10000149 10000148 10000151 10000151 10000150 10000151 10000149 10000150 10000149 10000151 10000149 10000151 10000149 10000151 10000149 10000149 10000149 10000151 10000150 10000149 10000150 10000149 10000150 10000149 10000149 10000149 10000149 10000149 10000148 10000148 10000150 10000148 10000149 10000149 10000149 10000150 10000149 10000148 10000148 10000149 10000149 10000150 10000150 10000149 10000149 10000149 10000150 10000149 10000149 10000149 10000149 10000149 10000148 10000148 10000149 10000148 10000149 10000149 10000149 10000149 10000149 10000148 10000148 10000149 10000149 10000150
min_time_thread_terminate_total=10000148
max_time_thread_terminate_total=10000151
first_time_thread_start_by_thread=3 3 4 3 3 3 4 3 4 3 3 4 4 3 3 3 3 3 3 4 3 3 4 4 3 3 4 3 3 3 4 3 4 3 3 4 4 3 3 3 3 3 3 4 3 3 4 4 3 4 3 4 3 4 4 4 3 3 4 4 3 3 3 3 4 3 3 3 3 3 4 3 4 4 3 4 3 4 4 4 3 3 4 4 3 3 3 3 4 3 3 3 3 3 4 3
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=999923248370
threads_final_size=999666
final_keysum=999923248370
final_size=999666
validate_result=success
Validation OK.
Structural validation OK.

total_find=828077556
total_rq=0
total_inserts=46009656
total_deletes=46008954
total_updates=92018610
total_queries=828077556
total_ops=920096166
find_throughput=82807755
rq_throughput=0
update_throughput=9201861
query_throughput=82807755
total_throughput=92009616


total find                    : 828077556
total rq                      : 0
total inserts                 : 46009656
total deletes                 : 46008954
total updates                 : 92018610
total queries                 : 828077556
total ops                     : 920096166
find throughput               : 82807755
rq throughput                 : 0
update throughput             : 9201861
query throughput              : 82807755
total throughput              : 92009616

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=12.9097
PAPI_L3_TCM=2.3063
PAPI_TOT_CYC=2908.13
PAPI_TOT_INS=372.498
garbage=414085131566920
total_execution_walltime=11.43s
REALTIME_MAIN_END_PERF_FORMAT=6985016.347076776
[time_cmd_output] time_elapsed_sec=11.56, faults_major=0, faults_minor=129142, mem_maxresident_kb=513908, user_cputime=1088.12, sys_cputime=5.74, percent_cpu=9456%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1