__step=000026
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 180 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" ./brown_int_bst_tm_auto.debra.tl2 -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000026.txt
__path_data=data/data000026.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992175.41238464
binary=./brown_int_bst_tm_auto.debra.tl2
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_int_bst_tm_auto
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=20000000
PREFILL_THREADS=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
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=32
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


TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.023s
prefilling_size=5165770 prefilling_elapsed_millis=1.001s
prefilling_size=5482534 prefilling_elapsed_millis=1.101s
prefilling_size=5777357 prefilling_elapsed_millis=1.201s
prefilling_size=6051355 prefilling_elapsed_millis=1.302s
prefilling_size=6307407 prefilling_elapsed_millis=1.402s
prefilling_size=6546941 prefilling_elapsed_millis=1.502s
prefilling_size=6770672 prefilling_elapsed_millis=1.602s
prefilling_size=6978134 prefilling_elapsed_millis=1.702s
prefilling_size=7172928 prefilling_elapsed_millis=1.803s
prefilling_size=7350250 prefilling_elapsed_millis=1.903s
prefilling_size=7514648 prefilling_elapsed_millis=2.003s
prefilling_size=7668873 prefilling_elapsed_millis=2.103s
prefilling_size=7815111 prefilling_elapsed_millis=2.203s
prefilling_size=7950289 prefilling_elapsed_millis=2.303s
prefilling_size=8077899 prefilling_elapsed_millis=2.404s
prefilling_size=8198884 prefilling_elapsed_millis=2.504s
prefilling_size=8311282 prefilling_elapsed_millis=2.604s
prefilling_size=8417554 prefilling_elapsed_millis=2.704s
prefilling_size=8516501 prefilling_elapsed_millis=2.804s
prefilling_size=8611275 prefilling_elapsed_millis=2.904s
prefilling_size=8698998 prefilling_elapsed_millis=3.005s
prefilling_size=8780122 prefilling_elapsed_millis=3.105s
prefilling_size=8857623 prefilling_elapsed_millis=3.205s
prefilling_size=8927965 prefilling_elapsed_millis=3.305s
prefilling_size=8996098 prefilling_elapsed_millis=3.405s
prefilling_size=9058197 prefilling_elapsed_millis=3.506s
prefilling_size=9118737 prefilling_elapsed_millis=3.606s
prefilling_size=9174381 prefilling_elapsed_millis=3.706s
prefilling_size=9226257 prefilling_elapsed_millis=3.806s
prefilling_size=9274365 prefilling_elapsed_millis=3.906s
prefilling_size=9319585 prefilling_elapsed_millis=4.007s
prefilling_size=9361800 prefilling_elapsed_millis=4.107s
prefilling_size=9401982 prefilling_elapsed_millis=4.207s
prefilling_size=9439920 prefilling_elapsed_millis=4.307s
prefilling_size=9476009 prefilling_elapsed_millis=4.407s
prefilling_size=9509356 prefilling_elapsed_millis=4.508s
prefilling_size=9538680 prefilling_elapsed_millis=4.608s
prefilling_size=9567263 prefilling_elapsed_millis=4.708s
prefilling_size=9597509 prefilling_elapsed_millis=4.808s
prefilling_size=9621575 prefilling_elapsed_millis=4.908s
prefilling_size=9645284 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 354731 keys with 48 threads...
tid=0 thread_prefill_amount=204134 percent_done_estimate=2762.2 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0673237s
prefilling_size=10000000 prefilling_elapsed_millis=0.067s
finished prefilling to size 10000000 for expected size 10000000 keysum=99985164257173, performing 67366720 updates; total_prefilling_elapsed_ms=67 ms)
pref_size=10000000
pref_millis=67
prefill_elapsed_ms=67
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 

global_epoch_counter=3064

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6992180.400161016
REALTIME_END_PERF_FORMAT=6992190.400240481

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10.1s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (48 threads)...
partitioned into 255 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.152s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 503 977 1846 3412 6129 10850 18635 30791 49216 75145 109676 153186 204451 261512 320855 376650 424856 460671 480823 484836 471641 442944 403363 357528 308335 260029 213451 170644 131994 99381 73079 51831 35896 23694 15068 9241 5529 3037 1591 833 386 198 87 38 12 2 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 21 65 146 379 778 1703 3551 6663 12549 21943 35758 54688 78788 107270 140777 174550 205724 232787 251157 261962 262580 253052 235242 213114 186405 159820 133540 109001 85656 65181 49195 35325 24938 16706 10775 6624 4176 2312 1247 657 278 153 69 33 10 1 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 505 998 1911 3558 6508 11628 20338 34342 55879 87694 131619 188944 259139 340300 428125 517427 599406 666395 713610 735993 733603 705524 656415 592770 521449 446434 373271 304184 240995 185037 138260 101026 71221 48632 31774 20016 12153 7213 3903 2080 1043 476 240 107 45 12 2 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 505 998 1911 3558 6508 11628 20338 34342 55879 87694 131619 188944 259139 340300 428125 517427 599406 666395 713610 735993 733603 705524 656415 592770 521449 446434 373271 304184 240995 185037 138260 101026 71221 48632 31774 20016 12153 7213 3903 2080 1043 476 240 107 45 12 2 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.98039 1.9802 1.93587 1.89587 1.87015 1.84496 1.81596 1.7723 1.73053 1.68859 1.64399 1.60225 1.56076 1.52423 1.48961 1.45915 1.43051 1.40296 1.37956 1.35758 1.338 1.31881 1.30257 1.28855 1.27653 1.26484 1.25366 1.24308 1.23128 1.2201 1.21011 1.20213 1.19193 1.17882 1.16615 1.15572 1.14548 1.13857 1.12006 1.12529 1.10096 1.08629 1.08824 1.08333 1.06542 1 1 1 1

tree_stats_height=57
tree_stats_numInternals=6555363
tree_stats_numLeaves=3447352
tree_stats_numNodes=10002715
tree_stats_numKeys=10002715

tree_stats_avgDegreeInternal=1.52588
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34464
tree_stats_avgKeyDepth=28.9302

4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 

global_epoch_counter=10449


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:48 2:1026 4:37622 8:274334 16:41327 32:1
    [2^00, 2^01]: 48
    (2^01, 2^02]: 1026
    (2^02, 2^03]: 37622
    (2^03, 2^04]: 274334
    (2^04, 2^05]: 41327
    (2^05, 2^06]: 1
sum_limbo_reclamation_event_size_total=4072609
count_limbo_reclamation_event_size_by_thread=7383 7382 7382 7382 7383 7383 7383 7382 7383 7382 7382 7383 7382 7383 7383 7382 7383 7383 7382 7383 7382 7383 7382 7381 7383 7383 7383 7382 7382 7382 7382 7382 7382 7383 7383 7383 7382 7383 7382 7382 7383 7382 7382 7383 7382 7383 7382 7383
count_limbo_reclamation_event_size_total=354358
sum_limbo_reclamation_event_count_total=354497
first_thread_announced_epoch_by_thread=20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898 20898
sum_num_inserts_by_thread=167225 167405 169992 169864 170316 169480 167598 170258 171172 171113 170947 169493 167779 171033 171355 171358 170709 169185 168009 170006 170100 171203 170062 169063 166454 169181 169825 171707 172195 168887 169122 170561 170648 172089 171414 170030 168415 169782 170510 171431 172584 169584 167990 169830 169613 171588 170133 169619
sum_num_inserts_total=8157917
sum_num_deletes_by_thread=166536 168639 169696 170849 169806 168439 168239 170022 170959 171312 170524 169812 168901 170343 171355 170432 171102 168830 167142 169608 170675 171012 170781 168410 166541 168872 169925 170910 171263 167675 168997 170393 170710 171642 171695 169630 168526 168818 170620 170726 172790 169723 167085 170522 169627 171281 170069 170006
sum_num_deletes_total=8151470
sum_num_searches_by_thread=3002857 3028619 3067561 3076583 3065397 3038432 3024867 3062833 3079470 3089636 3069600 3050889 3027991 3061415 3083870 3074434 3078461 3055040 3008792 3055704 3060176 3082662 3063649 3041996 3007739 3044978 3054389 3078063 3079624 3029064 3045726 3058961 3081089 3092894 3086409 3055639 3029254 3047640 3081986 3079487 3095802 3051832 3012636 3055824 3066149 3082049 3066570 3054914
sum_num_searches_total=146789652
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3336618 3364663 3407249 3417296 3405519 3376351 3360704 3403113 3421601 3432061 3411071 3390194 3364671 3402791 3426580 3416224 3420272 3393055 3343943 3395318 3400951 3424877 3404492 3379469 3340734 3383031 3394139 3420680 3423082 3365626 3383845 3399915 3422447 3436625 3429518 3395299 3366195 3386240 3423116 3421644 3441176 3391139 3347711 3396176 3405389 3424918 3406772 3394539
average_num_operations_total=3397896
stdev_num_operations_total=3849
sum_num_operations_total=163099039
min_num_operations_total=3336618
max_num_operations_total=3441176
first_time_thread_terminate_by_thread=10000134 10000079 10000079 10000079 10000079 10000079 10000080 10000079 10000080 10000079 10000079 10000079 10000080 10000079 10000080 10000080 10000079 10000079 10000078 10000080 10000079 10000079 10000080 10000079 10000079 10000079 10000079 10000080 10000080 10000079 10000080 10000080 10000079 10000079 10000079 10000079 10000079 10000079 10000080 10000081 10000079 10000080 10000078 10000080 10000080 10000081 10000079 10000079
min_time_thread_terminate_total=10000078
max_time_thread_terminate_total=10000134
first_time_thread_start_by_thread=10 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=100037587234797
threads_final_size=10002715
final_keysum=100037587234797
final_size=10002715
validate_result=success
Validation OK.
Structural validation OK.

total_find=146789652
total_rq=0
total_inserts=8157917
total_deletes=8151470
total_updates=16309387
total_queries=146789652
total_ops=163099039
find_throughput=14678965
rq_throughput=0
update_throughput=1630938
query_throughput=14678965
total_throughput=16309903


total find                    : 146789652
total rq                      : 0
total inserts                 : 8157917
total deletes                 : 8151470
total updates                 : 16309387
total queries                 : 146789652
total ops                     : 163099039
find throughput               : 14678965
rq throughput                 : 0
update throughput             : 1630938
query throughput              : 14678965
total throughput              : 16309903

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=42.2164
PAPI_L3_TCM=6.16161
PAPI_TOT_CYC=6689.27
PAPI_TOT_INS=2771.19
garbage=734101800993259
total_execution_walltime=15.668s
REALTIME_MAIN_END_PERF_FORMAT=6992190.710160657
TL2 system shutdown:
  GCLOCK=0x36588BA Starts=229979969 Aborts=197287
  Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=15.83, faults_major=0, faults_minor=239316, mem_maxresident_kb=635432, user_cputime=695.06, sys_cputime=45.96, percent_cpu=4681%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.tl2
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2