__step=000225
__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=data000225.txt
__path_data=data/data000225.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997188.152716783
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.006s
prefilling_size=551943 prefilling_elapsed_millis=1.001s
prefilling_size=586925 prefilling_elapsed_millis=1.101s
prefilling_size=618557 prefilling_elapsed_millis=1.201s
prefilling_size=648154 prefilling_elapsed_millis=1.301s
prefilling_size=675311 prefilling_elapsed_millis=1.402s
prefilling_size=700580 prefilling_elapsed_millis=1.502s
prefilling_size=723879 prefilling_elapsed_millis=1.602s
prefilling_size=744963 prefilling_elapsed_millis=1.702s
prefilling_size=764577 prefilling_elapsed_millis=1.802s
prefilling_size=782863 prefilling_elapsed_millis=1.903s
prefilling_size=800121 prefilling_elapsed_millis=2.003s
prefilling_size=815542 prefilling_elapsed_millis=2.103s
prefilling_size=829553 prefilling_elapsed_millis=2.203s
prefilling_size=842739 prefilling_elapsed_millis=2.303s
prefilling_size=854844 prefilling_elapsed_millis=2.404s
prefilling_size=866112 prefilling_elapsed_millis=2.504s
prefilling_size=876446 prefilling_elapsed_millis=2.604s
prefilling_size=886082 prefilling_elapsed_millis=2.704s
prefilling_size=894459 prefilling_elapsed_millis=2.804s
prefilling_size=903035 prefilling_elapsed_millis=2.904s
prefilling_size=910091 prefilling_elapsed_millis=3.005s
prefilling_size=916665 prefilling_elapsed_millis=3.105s
prefilling_size=923150 prefilling_elapsed_millis=3.205s
prefilling_size=928938 prefilling_elapsed_millis=3.305s
prefilling_size=934394 prefilling_elapsed_millis=3.405s
prefilling_size=939210 prefilling_elapsed_millis=3.506s
prefilling_size=944267 prefilling_elapsed_millis=3.606s
prefilling_size=948709 prefilling_elapsed_millis=3.706s
prefilling_size=952400 prefilling_elapsed_millis=3.806s
prefilling_size=955879 prefilling_elapsed_millis=3.906s
prefilling_size=959280 prefilling_elapsed_millis=4.007s
prefilling_size=962659 prefilling_elapsed_millis=4.107s
prefilling_size=965354 prefilling_elapsed_millis=4.207s
prefilling_size=968367 prefilling_elapsed_millis=4.307s
prefilling_size=970988 prefilling_elapsed_millis=4.407s
prefilling_size=973038 prefilling_elapsed_millis=4.508s
prefilling_size=975337 prefilling_elapsed_millis=4.608s
prefilling_size=977245 prefilling_elapsed_millis=4.708s
prefilling_size=979154 prefilling_elapsed_millis=4.808s
prefilling_size=981003 prefilling_elapsed_millis=4.908s
finished prefilling to size 981027 for expected size 1000000 keysum=981171625637, performing 7947869 updates; total_prefilling_elapsed_ms=5009 ms)
pref_size=981027
pref_millis=5009
prefill_elapsed_ms=5032
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=412
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6997193.361926464
REALTIME_END_PERF_FORMAT=6997203.362060588
###############################################################################
################################## 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 918 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.036s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 249 473 871 1572 2676 4320 6697 9901 13964 19009 24440 29902 35364 40402 44875 48151 50110 49764 47788 44254 39860 34566 28676 22439 16992 12257 8654 5910 3867 2528 1597 942 543 287 140 67 35 10 4 1 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 3 17 47 88 240 564 1059 1894 3163 4828 7303 10140 13035 16033 19050 22014 24533 26732 27263 27115 25542 23638 21186 18278 14511 11335 8208 5880 4113 2675 1757 1170 697 418 230 115 50 32 10 4 1 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 252 490 918 1660 2916 4884 7756 11795 17127 23837 31743 40042 48399 56435 63925 70165 74643 76496 75051 71369 65402 58204 49862 40717 31503 23592 16862 11790 7980 5203 3354 2112 1240 705 370 182 85 42 14 5 2 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 252 490 918 1660 2916 4884 7756 11795 17127 23837 31743 40042 48399 56435 63925 70165 74643 76496 75051 71369 65402 58204 49862 40717 31503 23592 16862 11790 7980 5203 3354 2112 1240 705 370 182 85 42 14 5 2 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.97656 1.95635 1.90816 1.85948 1.80964 1.7572 1.70352 1.6573 1.61263 1.57646 1.53421 1.49151 1.46194 1.43536 1.41682 1.39562 1.37757 1.3535 1.33057 1.3142 1.29632 1.28048 1.2628 1.24149 1.22261 1.2095 1.19519 1.18598 1.17557 1.16742 1.15875 1.15355 1.1411 1.13065 1.11773 1.11351 1.0989 1.08235 1.09524 1.07143 1.2 1 1
tree_stats_height=50
tree_stats_numInternals=654412
tree_stats_numLeaves=344973
tree_stats_numNodes=999385
tree_stats_numKeys=999385
tree_stats_avgDegreeInternal=1.52715
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34519
tree_stats_avgKeyDepth=25.0496
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=14154
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:277 2:6573 4:131913 8:330851 16:10244 32:7
[2^00, 2^01]: 277
(2^01, 2^02]: 6573
(2^02, 2^03]: 131913
(2^03, 2^04]: 330851
(2^04, 2^05]: 10244
(2^05, 2^06]: 7
sum_limbo_reclamation_event_size_total=4413024
count_limbo_reclamation_event_size_by_thread=9998 9997 9993 9997 9997 9997 9998 9998 9998 9998 9998 9995 9998 9998 9998 9998 9997 9998 9997 9997 9997 9994 9998 9995 9998 9998 9997 9998 9998 9996 9998 9998 9996 9996 9997 9998 9992 9998 9997 9998 9998 9998 9998 9997 9998 9998 9998 9998
count_limbo_reclamation_event_size_total=479865
sum_limbo_reclamation_event_count_total=659616
first_thread_announced_epoch_by_thread=28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308 28308
sum_num_inserts_by_thread=253446 256949 184186 243295 244731 223703 254457 248304 258424 261707 248497 236233 241109 255752 257268 256761 259882 258834 257084 239315 249064 257576 264236 262004 257780 257743 247082 264004 251196 257391 254696 252846 245200 264791 242901 261123 227222 259852 253292 265347 266898 269324 257592 234832 263618 262403 264037 267350
sum_num_inserts_total=12121337
sum_num_deletes_by_thread=253338 256944 183119 244401 245089 224546 254150 248466 260507 261511 248065 237269 240999 256121 257176 256567 259517 260132 256564 239897 249990 257506 265155 263640 257620 257566 246954 262838 251276 256578 253928 253368 244748 266027 243373 261132 227667 259980 254149 264994 267763 270089 258334 235653 264793 261223 265408 268019
sum_num_deletes_total=12134149
sum_num_searches_by_thread=4563140 4619777 3312056 4384891 4409325 4034662 4583071 4471560 4668977 4695198 4479165 4259843 4333779 4618260 4626011 4630553 4678411 4674376 4618539 4309492 4483245 4645318 4762871 4730473 4647014 4645252 4458236 4742273 4513591 4616795 4574729 4556174 4410317 4783669 4379339 4706212 4095705 4670531 4558514 4766762 4805880 4853802 4633786 4230471 4758610 4700618 4771069 4811105
sum_num_searches_total=218283447
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5069924 5133670 3679361 4872587 4899145 4482911 5091678 4968330 5187908 5218416 4975727 4733345 4815887 5130133 5140455 5143881 5197810 5193342 5132187 4788704 4982299 5160400 5292262 5256117 5162414 5160561 4952272 5269115 5016063 5130764 5083353 5062388 4900265 5314487 4865613 5228467 4550594 5190363 5065955 5297103 5340541 5393215 5149712 4700956 5287021 5224244 5300514 5346474
average_num_operations_total=5052894
stdev_num_operations_total=41952
sum_num_operations_total=242538933
min_num_operations_total=3679361
max_num_operations_total=5393215
first_time_thread_terminate_by_thread=10000137 10000133 10000144 10000133 10000144 10000148 10000133 10000147 10000145 10000134 10000138 10000133 10000134 10000133 10000146 10000133 10000149 10000135 10000134 10000148 10000151 10000135 10000138 10000133 10000143 10000133 10000147 10000133 10000135 10000136 10000141 10000139 10000133 10000134 10000136 10000133 10000135 10000133 10000133 10000133 10000133 10000140 10000150 10000133 10000133 10000150 10000133 10000145
min_time_thread_terminate_total=10000133
max_time_thread_terminate_total=10000151
first_time_thread_start_by_thread=3 3 2 3 3 3 2 2 3 3 3 3 2 3 3 3 2 3 3 2 2 3 3 2 3 3 2 3 3 3 2 2 3 3 3 3 2 3 3 3 2 3 3 2 2 3 3 2
min_time_thread_start_total=2
max_time_thread_start_total=3
sum_duration_all_ops_total=
threads_final_keysum=999076603181
threads_final_size=999385
final_keysum=999076603181
final_size=999385
validate_result=success
Validation OK.
Structural validation OK.
total_find=218283447
total_rq=0
total_inserts=12121337
total_deletes=12134149
total_updates=24255486
total_queries=218283447
total_ops=242538933
find_throughput=21828344
rq_throughput=0
update_throughput=2425548
query_throughput=21828344
total_throughput=24253893
total find : 218283447
total rq : 0
total inserts : 12121337
total deletes : 12134149
total updates : 24255486
total queries : 218283447
total ops : 242538933
find throughput : 21828344
rq throughput : 0
update throughput : 2425548
query throughput : 21828344
total throughput : 24253893
elapsed milliseconds : 10000
napping milliseconds overtime : 0
PAPI_L2_TCM=11.0198
PAPI_L3_TCM=0.285643
PAPI_TOT_CYC=5484.22
PAPI_TOT_INS=2245.25
garbage=108960191236189
total_execution_walltime=15.336s
REALTIME_MAIN_END_PERF_FORMAT=6997203.490093651
Hybrid noREC system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 246833281
[slow htm]
[fallback]
total fallback commit : 3653522
seconds global lock is held : 0.245311
[time_cmd_output] time_elapsed_sec=15.46, faults_major=0, faults_minor=84575, mem_maxresident_kb=340184, user_cputime=746.59, sys_cputime=4.54, percent_cpu=4857%
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=1