__step=000226
__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 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000226.txt
__path_data=data/data000226.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997203.680138527
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=2000000
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.009s
prefilling_size=555252 prefilling_elapsed_millis=1.001s
prefilling_size=589898 prefilling_elapsed_millis=1.101s
prefilling_size=621740 prefilling_elapsed_millis=1.201s
prefilling_size=651047 prefilling_elapsed_millis=1.301s
prefilling_size=678223 prefilling_elapsed_millis=1.401s
prefilling_size=703283 prefilling_elapsed_millis=1.502s
prefilling_size=726795 prefilling_elapsed_millis=1.602s
prefilling_size=748082 prefilling_elapsed_millis=1.702s
prefilling_size=767565 prefilling_elapsed_millis=1.802s
prefilling_size=785317 prefilling_elapsed_millis=1.902s
prefilling_size=801510 prefilling_elapsed_millis=2.003s
prefilling_size=817253 prefilling_elapsed_millis=2.103s
prefilling_size=832332 prefilling_elapsed_millis=2.203s
prefilling_size=845807 prefilling_elapsed_millis=2.303s
prefilling_size=858022 prefilling_elapsed_millis=2.403s
prefilling_size=869038 prefilling_elapsed_millis=2.504s
prefilling_size=879588 prefilling_elapsed_millis=2.604s
prefilling_size=888914 prefilling_elapsed_millis=2.704s
prefilling_size=897293 prefilling_elapsed_millis=2.804s
prefilling_size=905591 prefilling_elapsed_millis=2.904s
prefilling_size=913213 prefilling_elapsed_millis=3.005s
prefilling_size=920048 prefilling_elapsed_millis=3.105s
prefilling_size=926148 prefilling_elapsed_millis=3.205s
prefilling_size=931755 prefilling_elapsed_millis=3.305s
prefilling_size=937457 prefilling_elapsed_millis=3.405s
prefilling_size=942399 prefilling_elapsed_millis=3.506s
prefilling_size=946975 prefilling_elapsed_millis=3.606s
prefilling_size=951252 prefilling_elapsed_millis=3.706s
prefilling_size=955264 prefilling_elapsed_millis=3.806s
prefilling_size=958817 prefilling_elapsed_millis=3.906s
prefilling_size=962304 prefilling_elapsed_millis=4.007s
prefilling_size=965580 prefilling_elapsed_millis=4.107s
prefilling_size=968306 prefilling_elapsed_millis=4.207s
prefilling_size=970577 prefilling_elapsed_millis=4.307s
prefilling_size=972884 prefilling_elapsed_millis=4.407s
prefilling_size=975281 prefilling_elapsed_millis=4.508s
prefilling_size=977421 prefilling_elapsed_millis=4.608s
prefilling_size=979027 prefilling_elapsed_millis=4.708s
prefilling_size=980929 prefilling_elapsed_millis=4.808s
finished prefilling to size 980921 for expected size 1000000 keysum=980580410367, performing 7822927 updates; total_prefilling_elapsed_ms=4909 ms)
pref_size=980921
pref_millis=4909
prefill_elapsed_ms=4934
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=404
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6997208.773857167
REALTIME_END_PERF_FORMAT=6997218.773996778
###############################################################################
################################## 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 987 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 256 500 969 1782 3147 5331 8502 12955 18819 25945 33940 41931 49542 55286 58590 58822 56056 50698 43817 35885 28397 21383 15152 10471 6809 4193 2492 1356 690 344 164 74 31 15 7 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 9 18 86 249 506 1218 2227 3836 6371 9657 13966 18255 23391 27622 30866 32437 32270 30190 27044 22649 18484 14413 10319 7372 4850 3007 1874 1042 532 253 130 63 27 12 7 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 509 987 1868 3396 5837 9720 15182 22655 32316 43597 55897 67797 78677 86212 89688 88493 82968 74007 62929 51046 39867 29565 20790 14181 9043 5499 3230 1732 876 417 204 94 42 19 8 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 509 987 1868 3396 5837 9720 15182 22655 32316 43597 55897 67797 78677 86212 89688 88493 82968 74007 62929 51046 39867 29565 20790 14181 9043 5499 3230 1732 876 417 204 94 42 19 8 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.98828 1.95678 1.91084 1.86403 1.79211 1.75193 1.68724 1.63891 1.59576 1.54623 1.50364 1.46274 1.42974 1.39308 1.36072 1.33082 1.30411 1.28094 1.25825 1.24092 1.2247 1.20523 1.1907 1.17845 1.15753 1.14442 1.13421 1.11641 1.10739 1.08333 1.09592 1.09804 1.11702 1.09524 1.05263 1 1
tree_stats_height=45
tree_stats_numInternals=654607
tree_stats_numLeaves=345253
tree_stats_numNodes=999860
tree_stats_numKeys=999860
tree_stats_avgDegreeInternal=1.52742
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.3453
tree_stats_avgKeyDepth=23.3642
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=13110
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:247 2:6610 4:146831 8:320376 16:5820
[2^00, 2^01]: 247
(2^01, 2^02]: 6610
(2^02, 2^03]: 146831
(2^03, 2^04]: 320376
(2^04, 2^05]: 5820
sum_limbo_reclamation_event_size_total=4254742
count_limbo_reclamation_event_size_by_thread=9997 9998 9997 9998 9996 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9996 9997 9998 9997 9998 9998 9997 9998 9998 9997 9998 9998 9997 9998 9998 9998 9998 9998 9996 9998 9997 9998 9997 9998 9997 9996 9998 9998 9998 9998 9997
count_limbo_reclamation_event_size_total=479884
sum_limbo_reclamation_event_count_total=609888
first_thread_announced_epoch_by_thread=26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220 26220
sum_num_inserts_by_thread=226830 221512 220325 222039 222123 216135 230578 228537 224676 218520 222177 211614 226685 225501 219578 224024 217146 218645 228838 223229 223964 226107 227848 223135 231207 228558 228642 225583 223928 221362 237093 231065 230532 225491 227161 223796 231917 229608 227101 227011 224530 224952 236081 233550 230672 231676 221165 227798
sum_num_inserts_total=10830245
sum_num_deletes_by_thread=226690 222719 220193 220496 221274 215774 231298 228620 224835 218176 221496 212450 226926 224989 219743 223608 216200 218783 229779 222968 224907 226564 227513 221899 229814 230747 229817 225255 223340 221723 237671 230432 231034 226534 226862 223898 231920 230453 226926 227032 223909 224409 236067 233527 230024 231955 221930 225946
sum_num_deletes_total=10829125
sum_num_searches_by_thread=4076649 3987105 3964937 3986845 3968335 3881238 4157266 4120283 4040342 3930381 3994509 3819470 4084197 4059444 3962383 4022938 3894425 3945679 4121031 4023319 4033981 4064477 4104258 4009488 4162157 4134659 4128639 4061155 4024477 3989691 4278071 4154518 4158074 4062499 4095583 4021992 4174050 4145145 4086784 4075880 4042436 4046355 4254531 4206168 4140939 4174358 3986617 4079816
sum_num_searches_total=194937574
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4530169 4431336 4405455 4429380 4411732 4313147 4619142 4577440 4489853 4367077 4438182 4243534 4537808 4509934 4401704 4470570 4327771 4383107 4579648 4469516 4482852 4517148 4559619 4454522 4623178 4593964 4587098 4511993 4471745 4432776 4752835 4616015 4619640 4514524 4549606 4469686 4637887 4605206 4540811 4529923 4490875 4495716 4726679 4673245 4601635 4637989 4429712 4533560
average_num_operations_total=4512436
stdev_num_operations_total=15241
sum_num_operations_total=216596944
min_num_operations_total=4243534
max_num_operations_total=4752835
first_time_thread_terminate_by_thread=10000137 10000163 10000137 10000137 10000137 10000137 10000137 10000158 10000137 10000137 10000165 10000151 10000163 10000137 10000166 10000137 10000165 10000136 10000137 10000157 10000137 10000137 10000161 10000138 10000166 10000138 10000162 10000154 10000147 10000145 10000138 10000137 10000139 10000150 10000137 10000167 10000137 10000169 10000162 10000167 10000142 10000137 10000140 10000137 10000137 10000137 10000138 10000138
min_time_thread_terminate_total=10000136
max_time_thread_terminate_total=10000169
first_time_thread_start_by_thread=4 4 4 4 4 5 4 4 4 4 4 5 4 5 4 4 4 4 4 4 5 4 4 4 4 4 4 4 4 5 4 4 4 4 4 5 4 5 4 4 4 4 4 4 5 4 4 4
min_time_thread_start_total=4
max_time_thread_start_total=5
sum_duration_all_ops_total=
threads_final_keysum=999690632871
threads_final_size=999860
final_keysum=999690632871
final_size=999860
validate_result=success
Validation OK.
Structural validation OK.
total_find=194937574
total_rq=0
total_inserts=10830245
total_deletes=10829125
total_updates=21659370
total_queries=194937574
total_ops=216596944
find_throughput=19493757
rq_throughput=0
update_throughput=2165937
query_throughput=19493757
total_throughput=21659694
total find : 194937574
total rq : 0
total inserts : 10830245
total deletes : 10829125
total updates : 21659370
total queries : 194937574
total ops : 216596944
find throughput : 19493757
rq throughput : 0
update throughput : 2165937
query throughput : 19493757
total throughput : 21659694
elapsed milliseconds : 10000
napping milliseconds overtime : 0
PAPI_L2_TCM=11.695
PAPI_L3_TCM=0.286318
PAPI_TOT_CYC=6136.13
PAPI_TOT_INS=2266.52
garbage=95271832598717
total_execution_walltime=15.229s
REALTIME_MAIN_END_PERF_FORMAT=6997218.910640605
Hybrid noREC system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 220373807
[slow htm]
[fallback]
total fallback commit : 4046065
seconds global lock is held : 0.269972
[time_cmd_output] time_elapsed_sec=15.35, faults_major=0, faults_minor=84664, mem_maxresident_kb=340780, user_cputime=742.25, sys_cputime=3.96, percent_cpu=4859%
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