__step=000146
__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" ./ellen_ext_bst_lf.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000146.txt
__path_data=data/data000146.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6980584.892301824
binary=./ellen_ext_bst_lf.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=ellen_ext_bst_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=2000000
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 descriptor=64
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=999363 prefilling_elapsed_millis=1.001s
finished prefilling to size 999407 for expected size 1000000 keysum=999025977622, performing 26703334 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=999407
pref_millis=1102
prefill_elapsed_ms=1127
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=1299

6info_tIxPvE_object_size=64
6info_tIxPvE_allocated_count=0
6info_tIxPvE_allocated_size=0MB
6info_tIxPvE_get_from_pool=0
6info_tIxPvE_deallocated=0
6info_tIxPvE_limbo_count=0
6info_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=0

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6980586.226666223
REALTIME_END_PERF_FORMAT=6980596.226797803

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

tree_stats_computeWalltime=0.039s

tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 64 128 256 505 983 1840 3337 5821 9645 15239 22629 31547 41801 52565 62947 71473 77731 81815 82795 80082 74488 65819 55986 45582 35545 26678 19138 13126 8347 5126 2986 1662 849 400 144 52 13 0
tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 0 0 0 7 27 126 343 853 1997 4051 7849 13711 21293 31037 42183 54421 65215 73647 80835 85508 85676 83157 75652 66390 55619 44412 34218 25150 17905 11568 7266 4310 2475 1298 656 236 91 26
tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 128 256 512 1010 1966 3680 6674 11642 19290 30478 45258 63094 83602 105130 125894 142946 155462 163630 165590 160164 148976 131638 111972 91164 71090 53356 38276 26252 16694 10252 5972 3324 1698 800 288 104 26
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 7 27 126 343 853 1997 4051 7849 13711 21293 31037 42183 54421 65215 73647 80835 85508 85676 83157 75652 66390 55619 44412 34218 25150 17905 11568 7266 4310 2475 1298 656 236 91 26
tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 2 2 2 1.98633 1.97327 1.93591 1.90679 1.87219 1.82847 1.78999 1.74247 1.69705 1.66252 1.62875 1.59875 1.56772 1.54378 1.52627 1.50599 1.48362 1.46507 1.44181 1.4253 1.40708 1.3899 1.37527 1.35869 1.34293 1.31796 1.30706 1.29126 1.2783 1.25542 1.23557 1.18 1.18056 1.125 1

tree_stats_height=46
tree_stats_numInternals=999209
tree_stats_numLeaves=999210
tree_stats_numNodes=1998419
tree_stats_numKeys=999208

tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=0.999998
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=27.7574

tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 5120 10240 20480 40400 78640 147200 266960 465680 771600 1219120 1810320 2523760 3344080 4205200 5035760 5717840 6218480 6545200 6623600 6406560 5959040 5265520 4478880 3646560 2843600 2134240 1531040 1050080 667760 410080 238880 132960 67920 32000 11520 4160 1040
tree_stats_sizeInBytes=79936760

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

6info_tIxPvE_object_size=64
6info_tIxPvE_allocated_count=0
6info_tIxPvE_allocated_size=0MB
6info_tIxPvE_get_from_pool=0
6info_tIxPvE_deallocated=0
6info_tIxPvE_limbo_count=0
6info_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=0


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:83338 2:194488 4:138886 8:14829 16:25 32:50 64:44
    [2^00, 2^01]: 83338
    (2^01, 2^02]: 194488
    (2^02, 2^03]: 138886
    (2^03, 2^04]: 14829
    (2^04, 2^05]: 25
    (2^05, 2^06]: 50
    (2^06, 2^07]: 44
sum_limbo_reclamation_event_size_total=1378470
count_limbo_reclamation_event_size_by_thread=8924 8978 9032 9004 8996 8998 9012 9016 9072 9000 8882 8958 8978 9002 9022 9048 9020 9052 8974 9026 9084 8966 9006 8948 8908 8970 9028 9016 9024 9034 9000 9034 9014 8976 8952 9010 8948 8998 9020 9042 8962 8890 8958 8926 8962 9014 9016 8960
count_limbo_reclamation_event_size_total=431660
sum_limbo_reclamation_event_count_total=1680186
first_thread_announced_epoch_by_thread=37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602 37602
sum_num_inserts_by_thread=39835 40072 40171 40090 40108 39896 39740 40046 40396 40543 40273 39953 39839 40182 40520 40704 40280 40164 39623 40083 40165 40426 39884 39382 39546 40104 40427 40507 40175 39846 40008 40075 40527 40389 40155 39686 40132 40446 40359 40682 40693 39868 39373 39654 40484 40719 40258 39795
sum_num_inserts_total=1926283
sum_num_deletes_by_thread=39775 39684 40517 40547 40497 39577 39504 39963 40435 40731 39933 40156 40163 40254 40232 40398 40174 39885 39844 39995 40389 40701 40195 39723 40137 40235 40201 40112 40421 39890 40237 40146 40612 40828 40397 39919 39844 40262 40780 40767 40486 40079 39535 39946 40654 40454 39924 39813
sum_num_deletes_total=1928951
sum_num_searches_by_thread=7872757 7928294 8006723 7992536 7951644 7863303 7904370 7953111 8034870 8059502 7965378 7904456 7933802 7974906 8040398 8032904 7983913 7940631 7886469 7944123 7992220 8035026 7925387 7861996 7858075 7935090 7992109 7995700 7967944 7871360 7926449 7962248 8059211 8065406 7965003 7909780 7924387 7970201 8043492 8059081 7968369 7937688 7870002 7931220 7998246 8027180 7946998 7890930
sum_num_searches_total=382064888
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=7952367 8008050 8087411 8073173 8032249 7942776 7983614 8033120 8115701 8140776 8045584 7984565 8013804 8055342 8121150 8114006 8064367 8020680 7965936 8024201 8072774 8116153 8005466 7941101 7937758 8015429 8072737 8076319 8048540 7951096 8006694 8042469 8140350 8146623 8045555 7989385 8004363 8050909 8124631 8140530 8049548 8017635 7948910 8010820 8079384 8108353 8027180 7970538
average_num_operations_total=8040002
stdev_num_operations_total=8707
sum_num_operations_total=385920122
min_num_operations_total=7937758
max_num_operations_total=8146623
first_time_thread_terminate_by_thread=10000132 10000132 10000132 10000132 10000133 10000132 10000133 10000132 10000132 10000133 10000132 10000133 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000133 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000133 10000132 10000133 10000132 10000132 10000133 10000132 10000133 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000133 10000132 10000132 10000132
min_time_thread_terminate_total=10000132
max_time_thread_terminate_total=10000133
first_time_thread_start_by_thread=3 3 3 3 3 2 2 3 2 3 3 3 3 2 2 3 3 2 2 3 3 3 3 3 3 3 3 3 3 2 2 3 2 3 3 3 3 2 2 3 3 2 2 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=999562249632
threads_final_size=999208
final_keysum=999562249632
final_size=999208
validate_result=success
Validation OK.
Structural validation OK.

total_find=382064888
total_rq=0
total_inserts=1926283
total_deletes=1928951
total_updates=3855234
total_queries=382064888
total_ops=385920122
find_throughput=38206488
rq_throughput=0
update_throughput=385523
query_throughput=38206488
total_throughput=38592012


total find                    : 382064888
total rq                      : 0
total inserts                 : 1926283
total deletes                 : 1928951
total updates                 : 3855234
total queries                 : 382064888
total ops                     : 385920122
find throughput               : 38206488
rq throughput                 : 0
update throughput             : 385523
query throughput              : 38206488
total throughput              : 38592012

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=21.0051
PAPI_L3_TCM=4.16945
PAPI_TOT_CYC=3466.16
PAPI_TOT_INS=341.726
garbage=190927304295564
total_execution_walltime=11.477s
REALTIME_MAIN_END_PERF_FORMAT=6980596.373754589
[time_cmd_output] time_elapsed_sec=11.63, faults_major=0, faults_minor=99547, mem_maxresident_kb=400096, user_cputime=561.61, sys_cputime=2.01, percent_cpu=4845%
timeout=false
algorithm=ellen_ext_bst_lf.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2