__step=000442
__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.hybridnorec -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000442.txt
__path_data=data/data000442.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=7000124.792542996
binary=./brown_int_bst_tm_auto.debra.hybridnorec
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=200000
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


Hybrid noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.008s
prefilling_size=99832 prefilling_elapsed_millis=1.001s
finished prefilling to size 99830 for expected size 100000 keysum=9982184924, performing 1674756 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=99830
pref_millis=1102
prefill_elapsed_ms=1125
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=86

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=7000126.98244452
REALTIME_END_PERF_FORMAT=7000136.98409153

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

tree_stats_computeWalltime=0.034s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 63 125 236 430 749 1201 1775 2379 3038 3761 4446 4977 5311 5479 5519 5232 4854 4149 3487 2776 2127 1456 944 501 239 95 30 13 3 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 7 26 61 159 344 642 908 1243 1620 2096 2441 2711 2831 3061 2914 2900 2547 2190 1771 1467 1005 701 406 196 89 21 15 4 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 63 125 243 456 810 1360 2119 3021 3946 5004 6066 7073 7752 8190 8350 8293 7768 7049 6034 4966 3898 2923 1949 1202 645 291 119 34 18 5 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 63 125 243 456 810 1360 2119 3021 3946 5004 6066 7073 7752 8190 8350 8293 7768 7049 6034 4966 3898 2923 1949 1202 645 291 119 34 18 5 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 1.96875 1.98413 1.944 1.90535 1.83333 1.75432 1.675 1.58801 1.5187 1.49823 1.46063 1.43307 1.39234 1.37139 1.35055 1.33222 1.3058 1.28257 1.26741 1.24511 1.22594 1.20421 1.16866 1.13238 1.1198 1.08062 1.08247 1.03361 1.14706 1.11111 1 1

tree_stats_height=37
tree_stats_numInternals=65459
tree_stats_numLeaves=34377
tree_stats_numNodes=99836
tree_stats_numKeys=99836

tree_stats_avgDegreeInternal=1.52517
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34433
tree_stats_avgKeyDepth=19.8829

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:440 2:10342 4:188058 8:279177 16:1855
    [2^00, 2^01]: 440
    (2^01, 2^02]: 10342
    (2^02, 2^03]: 188058
    (2^03, 2^04]: 279177
    (2^04, 2^05]: 1855
sum_limbo_reclamation_event_size_total=3912785
count_limbo_reclamation_event_size_by_thread=9998 9997 9997 9998 9997 9996 9998 9997 9997 9997 9998 9997 9996 9997 9998 9998 9998 9998 9998 9998 9998 9998 9997 9996 9996 9998 9997 9998 9995 9996 9998 9997 9997 9997 9998 9997 9998 9997 9998 9998 9998 9998 9998 9997 9998 9996 9997 9998
count_limbo_reclamation_event_size_total=479872
sum_limbo_reclamation_event_count_total=584256
first_thread_announced_epoch_by_thread=24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516 24516
sum_num_inserts_by_thread=199029 198391 190398 201715 198595 188577 194464 197690 192264 196479 195519 197498 195494 196737 201448 198142 198387 197154 195953 199066 197575 200850 198386 195026 198033 203123 199652 201924 192136 204520 196541 199721 201522 204673 204038 198274 199178 200987 201191 204993 202154 196486 196958 193175 197376 200973 198346 200896
sum_num_inserts_total=9521707
sum_num_deletes_by_thread=198434 198760 191068 201158 197973 188435 195237 198291 191812 196732 195751 198220 194427 196557 201582 196346 197064 197033 197197 198778 198354 201120 197487 195630 197755 204231 200171 202690 192331 203894 196401 200553 201323 204714 203734 198865 199019 200632 200894 204825 203382 195882 198180 193739 197107 200723 198216 200464
sum_num_deletes_total=9523171
sum_num_searches_by_thread=3571900 3576107 3427393 3629606 3571478 3389145 3504021 3562279 3460840 3537046 3518262 3567609 3513427 3538261 3624197 3548858 3562572 3550741 3537334 3573506 3560623 3607980 3562603 3512402 3565689 3661258 3608291 3646351 3457388 3675951 3545662 3605331 3626403 3694385 3675701 3568692 3581274 3617044 3617042 3691607 3643023 3532847 3549374 3478478 3549746 3602068 3575436 3617236
sum_num_searches_total=171394467
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3969363 3973258 3808859 4032479 3968046 3766157 3893722 3958260 3844916 3930257 3909532 3963327 3903348 3931555 4027227 3943346 3958023 3944928 3930484 3971350 3956552 4009950 3958476 3903058 3961477 4068612 4008114 4050965 3841855 4084365 3938604 4005605 4029248 4103772 4083473 3965831 3979471 4018663 4019127 4101425 4048559 3925215 3944512 3865392 3944229 4003764 3971998 4018596
average_num_operations_total=3967486
stdev_num_operations_total=10565
sum_num_operations_total=190439345
min_num_operations_total=3766157
max_num_operations_total=4103772
first_time_thread_terminate_by_thread=10000166 10000173 10000162 10000162 10000162 10000174 10000175 10000187 10000189 10000188 10000185 10000185 10000171 10000162 10000167 10000176 10000165 10000162 10000172 10000161 10000162 10000182 10000170 10000184 10000162 10000188 10000161 10000181 10000174 10000191 10000163 10000188 10000162 10000162 10000162 10000186 10000180 10000163 10000162 10000162 10000170 10000162 10000189 10000179 10000164 10000166 10000177 10000163
min_time_thread_terminate_total=10000161
max_time_thread_terminate_total=10000191
first_time_thread_start_by_thread=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 3 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=9986085670
threads_final_size=99836
final_keysum=9986085670
final_size=99836
validate_result=success
Validation OK.
Structural validation OK.

total_find=171394467
total_rq=0
total_inserts=9521707
total_deletes=9523171
total_updates=19044878
total_queries=171394467
total_ops=190439345
find_throughput=17139446
rq_throughput=0
update_throughput=1904487
query_throughput=17139446
total_throughput=19043934


total find                    : 171394467
total rq                      : 0
total inserts                 : 9521707
total deletes                 : 9523171
total updates                 : 19044878
total queries                 : 171394467
total ops                     : 190439345
find throughput               : 17139446
rq throughput                 : 0
update throughput             : 1904487
query throughput              : 17139446
total throughput              : 19043934

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=8.30911
PAPI_L3_TCM=0.000713203
PAPI_TOT_CYC=6988.01
PAPI_TOT_INS=2354.09
garbage=8566964783714
total_execution_walltime=11.437s
REALTIME_MAIN_END_PERF_FORMAT=7000136.230359112
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 188593495
[slow htm]
[fallback]
total fallback commit         : 3520607
seconds global lock is held   : 0.202726
[time_cmd_output] time_elapsed_sec=11.55, faults_major=0, faults_minor=76650, mem_maxresident_kb=308628, user_cputime=560.95, sys_cputime=3.23, percent_cpu=4880%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.hybridnorec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2