__step=000508
__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=data000508.txt
__path_data=data/data000508.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6985016.552132603
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.009s
prefilling_size=999577 prefilling_elapsed_millis=1.001s
finished prefilling to size 999513 for expected size 1000000 keysum=999376312469, performing 54320338 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=999513
pref_millis=1102
prefill_elapsed_ms=1126
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=652

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6985017.876527306
REALTIME_END_PERF_FORMAT=6985027.876666086

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

tree_stats_computeWalltime=0.035s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 123 242 464 869 1562 2698 4468 7066 10778 15540 21239 27646 34094 40380 45814 50052 52437 52674 51165 47047 41191 34718 28420 22340 17255 12995 9747 7221 5296 3713 2504 1532 872 416 191 93 47 20 9 4 1 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 3 2 10 38 95 240 494 1038 1862 3312 5333 7981 11404 14900 18554 22223 25340 27572 28673 29286 27897 25024 21398 18000 14309 11288 8369 6359 4740 3581 2568 1815 1125 689 336 143 70 37 14 5 5 0 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 126 244 474 907 1657 2938 4962 8104 12640 18852 26572 35627 45498 55280 64368 72275 77777 80246 79838 76333 69088 59742 49818 40340 31564 24283 18116 13580 10036 7294 5072 3347 1997 1105 527 236 117 57 23 9 6 1 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 126 244 474 907 1657 2938 4962 8104 12640 18852 26572 35627 45498 55280 64368 72275 77777 80246 79838 76333 69088 59742 49818 40340 31564 24283 18116 13580 10036 7294 5072 3347 1997 1105 527 236 117 57 23 9 6 1 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.96875 1.96032 1.95082 1.9346 1.8688 1.83042 1.77059 1.73277 1.68781 1.63877 1.58519 1.54147 1.50108 1.46565 1.43394 1.41109 1.3836 1.35755 1.33851 1.31524 1.28875 1.26851 1.25275 1.23927 1.22866 1.22266 1.21089 1.21158 1.20729 1.19908 1.18632 1.16621 1.13893 1.11668 1.10045 1.08539 1.10169 1.08547 1.05263 1 1.22222 1 1 1

tree_stats_height=50
tree_stats_numInternals=655071
tree_stats_numLeaves=346133
tree_stats_numNodes=1001204
tree_stats_numKeys=1001204

tree_stats_avgDegreeInternal=1.52839
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34572
tree_stats_avgKeyDepth=24.51

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24192 46848 91008 174144 318144 564096 952704 1555968 2426880 3619584 5101824 6840384 8735616 10613760 12358656 13876800 14933184 15407232 15328896 14655936 13264896 11470464 9565056 7745280 6060288 4662336 3478272 2607360 1926912 1400448 973824 642624 383424 212160 101184 45312 22464 10944 4416 1728 1152 192 192
tree_stats_sizeInBytes=192231168

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=10932


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:1 4:84 8:44281 16:876333 32:39014 64:8 128:87
    (2^01, 2^02]: 1
    (2^02, 2^03]: 84
    (2^03, 2^04]: 44281
    (2^04, 2^05]: 876333
    (2^05, 2^06]: 39014
    (2^06, 2^07]: 8
    (2^07, 2^08]: 87
sum_limbo_reclamation_event_size_total=22165295
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=986878
first_thread_announced_epoch_by_thread=21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864 21864
sum_num_inserts_by_thread=466819 469534 476111 480190 472816 466897 470728 472747 478769 483974 476784 469561 475739 472519 480478 475656 478217 471744 466544 474866 480921 475916 470886 466599 470792 470715 478105 474836 472702 467005 469989 472454 483118 479715 474558 474369 469546 474989 480642 481066 473701 470986 470255 473624 473586 475433 474247 471268 468695 475883 475492 477967 469906 470610 475981 478730 483619 478738 479693 473894 474539 472619 481269 476380 473926 473438 472781 477052 477773 477937 473457 470002 467357 474789 479925 474977 471350 475568 473477 471019 479968 478842 475024 471541 472271 478437 482290 482105 473116 473136 466685 475519 482715 479584 478730 475268
sum_num_inserts_total=45576790
sum_num_deletes_by_thread=468497 471501 476178 480396 474491 467515 470972 473449 476603 483057 476797 469941 475559 473363 480685 476588 480612 470741 469143 474245 480868 475562 471450 466547 471401 468885 478388 474820 472237 467174 470569 472793 483495 478679 474708 474324 471343 473809 479704 481982 473542 469976 471630 473135 474032 475427 473389 470293 467850 476349 475935 479166 471683 470549 474809 478012 483162 478585 479418 475565 476167 471566 482824 476928 474736 473829 473433 477130 476948 477351 473244 469184 467003 475845 480171 474302 470535 474633 474001 471438 480063 478311 475495 471003 472106 477789 482514 481388 473703 472606 466571 474873 482016 476961 479863 476472
sum_num_deletes_total=45584580
sum_num_searches_by_thread=8410785 8477319 8564518 8643769 8527119 8421182 8466734 8510886 8612449 8696723 8594336 8460223 8550306 8516727 8654490 8586621 8621820 8481972 8419182 8546843 8631837 8558959 8483143 8412812 8471203 8456413 8625308 8548477 8493961 8417270 8465976 8519432 8699639 8628242 8528744 8542722 8474657 8511227 8671668 8671290 8529362 8476569 8482645 8522948 8521254 8556886 8548939 8476952 8431853 8559350 8557031 8626706 8466165 8464331 8544408 8610649 8700834 8637702 8623916 8556266 8553876 8511597 8679072 8586239 8543063 8529191 8506551 8569905 8595161 8584883 8511405 8456233 8415651 8554952 8641055 8543033 8470281 8547048 8543180 8490784 8635394 8624257 8549539 8492416 8480012 8610133 8675443 8684139 8510903 8513989 8395050 8556124 8681268 8610272 8609906 8565243
sum_num_searches_total=820498998
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=9346101 9418354 9516807 9604355 9474426 9355594 9408434 9457082 9567821 9663754 9547917 9399725 9501604 9462609 9615653 9538865 9580649 9424457 9354869 9495954 9593626 9510437 9425479 9345958 9413396 9396013 9581801 9498133 9438900 9351449 9406534 9464679 9666252 9586636 9478010 9491415 9415546 9460025 9632014 9634338 9476605 9417531 9424530 9469707 9468872 9507746 9496575 9418513 9368398 9511582 9508458 9583839 9407754 9405490 9495198 9567391 9667615 9595025 9583027 9505725 9504582 9455782 9643165 9539547 9491725 9476458 9452765 9524087 9549882 9540171 9458106 9395419 9350011 9505586 9601151 9492312 9412166 9497249 9490658 9433241 9595425 9581410 9500058 9434960 9424389 9566359 9640247 9647632 9457722 9459731 9328306 9506516 9645999 9566817 9568499 9516983
average_num_operations_total=9496462
stdev_num_operations_total=8687
sum_num_operations_total=911660368
min_num_operations_total=9328306
max_num_operations_total=9667615
first_time_thread_terminate_by_thread=10000139 10000138 10000138 10000138 10000138 10000139 10000138 10000139 10000138 10000138 10000140 10000138 10000139 10000139 10000138 10000139 10000140 10000139 10000138 10000138 10000139 10000138 10000138 10000138 10000139 10000138 10000138 10000138 10000138 10000139 10000138 10000138 10000139 10000138 10000138 10000138 10000139 10000138 10000138 10000139 10000140 10000140 10000138 10000138 10000139 10000138 10000138 10000138 10000138 10000139 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000139 10000139 10000138 10000140 10000138 10000138 10000138 10000139 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000139 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000139 10000139 10000138 10000140 10000138 10000138 10000138 10000139 10000138 10000138 10000138 10000138 10000138 10000138
min_time_thread_terminate_total=10000138
max_time_thread_terminate_total=10000140
first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4
min_time_thread_start_total=4
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=1000697307918
threads_final_size=1001204
final_keysum=1000697307918
final_size=1001204
validate_result=success
Validation OK.
Structural validation OK.

total_find=820498998
total_rq=0
total_inserts=45576790
total_deletes=45584580
total_updates=91161370
total_queries=820498998
total_ops=911660368
find_throughput=82049899
rq_throughput=0
update_throughput=9116137
query_throughput=82049899
total_throughput=91166036


total find                    : 820498998
total rq                      : 0
total inserts                 : 45576790
total deletes                 : 45584580
total updates                 : 91161370
total queries                 : 820498998
total ops                     : 911660368
find throughput               : 82049899
rq throughput                 : 0
update throughput             : 9116137
query throughput              : 82049899
total throughput              : 91166036

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=12.9936
PAPI_L3_TCM=2.3085
PAPI_TOT_CYC=2935.27
PAPI_TOT_INS=377.454
garbage=410307054489388
total_execution_walltime=11.482s
REALTIME_MAIN_END_PERF_FORMAT=6985028.35315591
[time_cmd_output] time_elapsed_sec=11.63, faults_major=0, faults_minor=128871, mem_maxresident_kb=513044, user_cputime=1089.96, sys_cputime=3.78, percent_cpu=9403%
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=2