__step=000042
__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" ./guerraoui_ext_bst_ticket.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000042.txt
__path_data=data/data000042.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979251.401061811
binary=./guerraoui_ext_bst_ticket.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=guerraoui_ext_bst_ticket
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=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
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
sizes: node=40
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
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.005s
prefilling_size=6706670 prefilling_elapsed_millis=1.001s
prefilling_size=6997300 prefilling_elapsed_millis=1.101s
prefilling_size=7259909 prefilling_elapsed_millis=1.201s
prefilling_size=7497064 prefilling_elapsed_millis=1.301s
prefilling_size=7711082 prefilling_elapsed_millis=1.402s
prefilling_size=7904366 prefilling_elapsed_millis=1.502s
prefilling_size=8081432 prefilling_elapsed_millis=1.602s
prefilling_size=8244341 prefilling_elapsed_millis=1.702s
prefilling_size=8390682 prefilling_elapsed_millis=1.802s
prefilling_size=8526141 prefilling_elapsed_millis=1.903s
prefilling_size=8648851 prefilling_elapsed_millis=2.003s
prefilling_size=8761219 prefilling_elapsed_millis=2.103s
prefilling_size=8863467 prefilling_elapsed_millis=2.203s
prefilling_size=8956893 prefilling_elapsed_millis=2.303s
prefilling_size=9042722 prefilling_elapsed_millis=2.404s
prefilling_size=9122565 prefilling_elapsed_millis=2.504s
prefilling_size=9193415 prefilling_elapsed_millis=2.604s
prefilling_size=9259609 prefilling_elapsed_millis=2.704s
prefilling_size=9318815 prefilling_elapsed_millis=2.804s
prefilling_size=9374336 prefilling_elapsed_millis=2.905s
prefilling_size=9425462 prefilling_elapsed_millis=3.005s
prefilling_size=9473075 prefilling_elapsed_millis=3.105s
prefilling_size=9516302 prefilling_elapsed_millis=3.205s
prefilling_size=9556254 prefilling_elapsed_millis=3.305s
prefilling_size=9591703 prefilling_elapsed_millis=3.405s
prefilling_size=9623854 prefilling_elapsed_millis=3.506s
prefilling_size=9652249 prefilling_elapsed_millis=3.606s
prefilling_size=9681314 prefilling_elapsed_millis=3.706s
prefilling_size=9706666 prefilling_elapsed_millis=3.806s
prefilling_size=9729754 prefilling_elapsed_millis=3.907s
prefilling_size=9752273 prefilling_elapsed_millis=4.007s
prefilling_size=9772633 prefilling_elapsed_millis=4.107s
prefilling_size=9790855 prefilling_elapsed_millis=4.207s
prefilling_size=9807231 prefilling_elapsed_millis=4.307s
finished prefilling to size 9807286 for expected size 10000000 keysum=98071001270431, performing 78638195 updates; total_prefilling_elapsed_ms=4408 ms)
pref_size=9807286
pref_millis=4408
prefill_elapsed_ms=4431
6node_tIxPvE_object_size=40
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=3608
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6979256.13895859
REALTIME_END_PERF_FORMAT=6979266.14030743
###############################################################################
################################## 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 968 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.175s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 63 126 248 484 943 1823 3438 6349 11399 19640 32544 52013 79702 117328 165494 224656 293414 368760 448676 527937 600044 659124 701534 719987 714225 688022 639508 574668 498890 418541 339104 266044 201368 148234 105777 72687 48062 30453 18721 11022 6350 3534 1864 956 490 247 140 75 35 15 8 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 1 0 4 12 25 63 208 527 1299 3158 6736 13075 24324 42076 69162 106332 155898 218068 288844 369415 455830 540964 616714 683081 725749 740428 736536 704348 650446 579239 497978 412164 330720 254502 190691 138867 97312 65671 42185 26420 15694 9166 5204 2772 1422 733 354 205 115 55 22 15 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 126 252 496 968 1886 3646 6876 12698 22798 39280 65088 104026 159404 234656 330988 449312 586828 737520 897352 1055874 1200088 1318248 1403068 1439974 1428450 1376044 1279016 1149336 997780 837082 678208 532088 402736 296468 211554 145374 96124 60906 37442 22044 12700 7068 3728 1912 980 494 280 150 70 30 16 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 1 0 4 12 25 63 208 527 1299 3157 6736 13075 24324 42076 69162 106332 155898 218068 288844 369415 455830 540964 616714 683081 725749 740428 736536 704348 650446 579239 497978 412164 330720 254502 190691 138867 97312 65671 42185 26420 15694 9166 5204 2772 1422 733 354 205 115 55 22 15 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.98438 2 1.98413 1.97581 1.97417 1.9666 1.94295 1.92336 1.8977 1.86144 1.82851 1.79912 1.76617 1.73604 1.70526 1.67874 1.65303 1.6284 1.60836 1.58833 1.56829 1.54923 1.53217 1.51315 1.496 1.48166 1.46474 1.4493 1.43407 1.41947 1.4051 1.39227 1.37845 1.36807 1.35679 1.34359 1.33061 1.31681 1.30738 1.29438 1.28806 1.27827 1.26372 1.25644 1.25628 1.25204 1.2834 1.26786 1.23333 1.21429 1.26667 1.0625 1
tree_stats_height=59
tree_stats_numInternals=9824830
tree_stats_numLeaves=9824831
tree_stats_numNodes=19649661
tree_stats_numKeys=9824830
tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=31.346
tree_stats_bytesAtDepth=40 80 160 320 640 1280 2560 5040 10080 19840 38720 75440 145840 275040 507920 911920 1571200 2603520 4161040 6376160 9386240 13239520 17972480 23473120 29500800 35894080 42234960 48003520 52729920 56122720 57598960 57138000 55041760 51160640 45973440 39911200 33483280 27128320 21283520 16109440 11858720 8462160 5814960 3844960 2436240 1497680 881760 508000 282720 149120 76480 39200 19760 11200 6000 2800 1200 640 80
tree_stats_sizeInBytes=785986440
6node_tIxPvE_object_size=40
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=12213
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:150754 4:117369 8:11658 16:23
(2^01, 2^02]: 150754
(2^02, 2^03]: 117369
(2^03, 2^04]: 11658
(2^04, 2^05]: 23
sum_limbo_reclamation_event_size_total=934488
count_limbo_reclamation_event_size_by_thread=5798 5810 5781 5867 5819 5856 5834 5865 5827 5923 5911 5792 5812 5823 5832 5856 5852 5805 5802 5840 5821 5825 5807 5853 5853 5849 5887 5815 5821 5822 5721 5868 5845 5754 5867 5775 5874 5816 5902 5798 5849 5858 5733 5801 5934 5842 5835 5674
count_limbo_reclamation_event_size_total=279804
sum_limbo_reclamation_event_count_total=413040
first_thread_announced_epoch_by_thread=24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426 24426
sum_num_inserts_by_thread=19500 20056 19785 19929 19890 19468 19745 19839 19977 20183 19613 19866 19713 19914 20007 20044 19869 19686 19659 19753 20028 20217 19751 19345 19483 19830 19947 19763 19959 19868 19988 19855 20035 19929 19719 19565 19581 19614 19872 19791 19936 19904 19726 19909 20132 19868 19773 19723
sum_num_inserts_total=951607
sum_num_deletes_by_thread=19628 19788 19794 19922 19969 19623 19647 19676 19925 20156 19790 19487 19712 19897 19918 19762 20033 19964 19465 19573 19756 19815 19695 19592 19932 19942 20289 19899 19837 19652 19564 19907 19983 19747 19745 19542 20055 19754 20350 19971 19864 20014 19656 19636 20205 20089 19728 19424
sum_num_deletes_total=951372
sum_num_searches_by_thread=3899092 3917833 3948736 3945094 3930849 3890142 3907004 3923844 3963370 3964793 3924250 3905134 3918482 3939302 3956105 3959087 3937533 3912838 3891616 3908588 3936482 3948176 3911148 3881782 3905616 3921860 3951738 3946665 3933194 3895946 3911501 3925021 3963685 3966734 3923230 3894459 3920304 3939872 3958314 3958638 3935584 3913081 3892580 3911360 3935713 3953145 3911492 3882870
sum_num_searches_total=188473882
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3938220 3957677 3988315 3984945 3970708 3929233 3946396 3963359 4003272 4005132 3963653 3944487 3957907 3979113 3996030 3998893 3977435 3952488 3930740 3947914 3976266 3988208 3950594 3920719 3945031 3961632 3991974 3986327 3972990 3935466 3951053 3964783 4003703 4006410 3962694 3933566 3959940 3979240 3998536 3998400 3975384 3952999 3931962 3950905 3976050 3993102 3950993 3922017
average_num_operations_total=3966184
stdev_num_operations_total=3539
sum_num_operations_total=190376861
min_num_operations_total=3920719
max_num_operations_total=4006410
first_time_thread_terminate_by_thread=10000136 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000136 10000135 10000135 10000135 10000135 10000136 10000135 10000135 10000136 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135
min_time_thread_terminate_total=10000135
max_time_thread_terminate_total=10000136
first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3
min_time_thread_start_total=3
max_time_thread_start_total=3
sum_duration_all_ops_total=
threads_final_keysum=98243688743090
threads_final_size=9824830
final_keysum=98243688743090
final_size=9824830
validate_result=success
Validation OK.
Structural validation OK.
total_find=188473882
total_rq=0
total_inserts=951607
total_deletes=951372
total_updates=1902979
total_queries=188473882
total_ops=190376861
find_throughput=18847388
rq_throughput=0
update_throughput=190297
query_throughput=18847388
total_throughput=19037686
total find : 188473882
total rq : 0
total inserts : 951607
total deletes : 951372
total updates : 1902979
total queries : 188473882
total ops : 190376861
find throughput : 18847388
rq throughput : 0
update throughput : 190297
query throughput : 18847388
total throughput : 19037686
elapsed milliseconds : 10000
napping milliseconds overtime : 0
PAPI_L2_TCM=37.655
PAPI_L3_TCM=13.5639
PAPI_TOT_CYC=7028.92
PAPI_TOT_INS=345.737
garbage=905799026053203
total_execution_walltime=14.886s
REALTIME_MAIN_END_PERF_FORMAT=6979266.288016477
[time_cmd_output] time_elapsed_sec=15.10, faults_major=0, faults_minor=307380, mem_maxresident_kb=1231516, user_cputime=724.39, sys_cputime=3.45, percent_cpu=4820%
timeout=false
algorithm=guerraoui_ext_bst_ticket.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2