__step=000841
__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 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000841.txt
__path_data=data/data000841.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6989112.722763556
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=50
DEL_FRAC=50
RQ=0
RQSIZE=0
MAXKEY=2000000
PREFILL_THREADS=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
RQ_THREADS=0
distribution=0
INS_DEL_FRAC=50.0 50.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


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.006s
prefilling_size=998060 prefilling_elapsed_millis=1.001s
finished prefilling to size 998006 for expected size 1000000 keysum=997994829989, performing 35559841 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=998006
pref_millis=1102
prefill_elapsed_ms=1121
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=1643

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6989114.34073797
REALTIME_END_PERF_FORMAT=6989124.34250309

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

tree_stats_computeWalltime=0.038s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 247 485 926 1733 3123 5397 8873 13643 19658 26678 33825 40300 45434 48827 49883 49596 47822 44542 40094 35198 29677 24753 20303 16204 12849 10136 7723 5810 4228 2880 1851 1138 655 366 188 95 54 14 4 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 6 5 28 79 170 468 1080 2156 4243 6876 10506 14616 18775 22427 25334 26360 27117 27024 25774 23548 21291 18164 15172 12748 10290 8108 6500 5086 3807 2865 2004 1297 868 497 275 131 62 49 13 3 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 253 490 954 1812 3293 5865 9953 15799 23901 33554 44331 54916 64209 71254 75217 75956 74939 71566 65868 58746 50968 42917 35475 28952 23139 18244 14223 10896 8035 5745 3855 2435 1523 863 463 226 116 63 17 4 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 253 490 954 1812 3293 5865 9953 15799 23901 33554 44331 54916 64209 71254 75217 75956 74939 71566 65868 58746 50968 42917 35475 28952 23139 18244 14223 10896 8035 5745 3855 2435 1523 863 463 226 116 63 17 4 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.97656 1.96047 1.95714 1.92872 1.86093 1.83268 1.77681 1.69587 1.64928 1.5814 1.52611 1.47576 1.43537 1.40212 1.37037 1.34664 1.33365 1.31684 1.29799 1.28317 1.26844 1.25977 1.24983 1.24381 1.23953 1.23316 1.22402 1.22309 1.2042 1.1888 1.16971 1.15149 1.15811 1.13657 1.1124 1.08207 1.09292 1.07759 1.04762 1 1 1

tree_stats_height=49
tree_stats_numInternals=655468
tree_stats_numLeaves=345823
tree_stats_numNodes=1001291
tree_stats_numKeys=1001291

tree_stats_avgDegreeInternal=1.5276
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34538
tree_stats_avgKeyDepth=24.0799

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 48576 94080 183168 347904 632256 1126080 1910976 3033408 4588992 6442368 8511552 10543872 12328128 13680768 14441664 14583552 14388288 13740672 12646656 11279232 9785856 8240064 6811200 5558784 4442688 3502848 2730816 2092032 1542720 1103040 740160 467520 292416 165696 88896 43392 22272 12096 3264 768 192
tree_stats_sizeInBytes=192247872

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:1 4:0 8:11 16:38 32:188 64:448601 128:30965 256:1 512:46 1024:47
    (2^01, 2^02]: 1
    (2^02, 2^03]: 0
    (2^03, 2^04]: 11
    (2^04, 2^05]: 38
    (2^05, 2^06]: 188
    (2^06, 2^07]: 448601
    (2^07, 2^08]: 30965
    (2^08, 2^09]: 1
    (2^09, 2^10]: 46
    (2^10, 2^11]: 47
sum_limbo_reclamation_event_size_total=54374434
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 9997 9998 9997 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9995 9998 9998
count_limbo_reclamation_event_size_total=479898
sum_limbo_reclamation_event_count_total=771068
first_thread_announced_epoch_by_thread=35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414 35414
sum_num_inserts_by_thread=3604223 3622387 3653346 3659275 3647501 3610868 3625236 3636393 3667887 3682772 3637679 3622205 3629161 3648691 3662006 3672631 3644663 3626824 3610545 3636619 3650216 3661671 3632714 3588711 3608418 3634125 3663282 3653800 3635209 3602557 3626277 3646692 3681045 3680698 3639768 3617781 3626675 3647153 3676853 3667679 3658376 3633555 3610307 3629534 3657969 3667312 3632316 3602266
sum_num_inserts_total=174733871
sum_num_deletes_by_thread=3604689 3623889 3656251 3657812 3642789 3608426 3628739 3636269 3668430 3681783 3635603 3614980 3629049 3651173 3659303 3673881 3648534 3627120 3610147 3635406 3652686 3662432 3631468 3587879 3607370 3633658 3665026 3653414 3634727 3601899 3625647 3648434 3678592 3681832 3638944 3618116 3626471 3639835 3673721 3670134 3659381 3634410 3611315 3632049 3659027 3663532 3636538 3600787
sum_num_deletes_total=174723597
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=7208912 7246276 7309597 7317087 7290290 7219294 7253975 7272662 7336317 7364555 7273282 7237185 7258210 7299864 7321309 7346512 7293197 7253944 7220692 7272025 7302902 7324103 7264182 7176590 7215788 7267783 7328308 7307214 7269936 7204456 7251924 7295126 7359637 7362530 7278712 7235897 7253146 7286988 7350574 7337813 7317757 7267965 7221622 7261583 7316996 7330844 7268854 7203053
average_num_operations_total=7280363
stdev_num_operations_total=6851
sum_num_operations_total=349457468
min_num_operations_total=7176590
max_num_operations_total=7364555
first_time_thread_terminate_by_thread=10000174 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000176 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000174 10000175 10000175 10000175 10000175 10000175 10000174 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175 10000175
min_time_thread_terminate_total=10000174
max_time_thread_terminate_total=10000176
first_time_thread_start_by_thread=3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 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=2
max_time_thread_start_total=3
sum_duration_all_ops_total=

threads_final_keysum=1001157179257
threads_final_size=1001291
final_keysum=1001157179257
final_size=1001291
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=174733871
total_deletes=174723597
total_updates=349457468
total_queries=0
total_ops=349457468
find_throughput=0
rq_throughput=0
update_throughput=34945746
query_throughput=0
total_throughput=34945746


total find                    : 0
total rq                      : 0
total inserts                 : 174733871
total deletes                 : 174723597
total updates                 : 349457468
total queries                 : 0
total ops                     : 349457468
find throughput               : 0
rq throughput                 : 0
update throughput             : 34945746
query throughput              : 0
total throughput              : 34945746

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=17.4834
PAPI_L3_TCM=4.50631
PAPI_TOT_CYC=3828.09
PAPI_TOT_INS=534.896
garbage=0
total_execution_walltime=11.455s
REALTIME_MAIN_END_PERF_FORMAT=6989124.179046921
[time_cmd_output] time_elapsed_sec=11.59, faults_major=0, faults_minor=127667, mem_maxresident_kb=508976, user_cputime=560.26, sys_cputime=2.57, percent_cpu=4854%
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