__step=000306
__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_abtree_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=data000306.txt
__path_data=data/data000306.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6998406.339762233
binary=./brown_abtree_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_abtree_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=200
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.018s
prefilling_size=584739 prefilling_elapsed_millis=1.001s
prefilling_size=619422 prefilling_elapsed_millis=1.101s
prefilling_size=651358 prefilling_elapsed_millis=1.201s
prefilling_size=680189 prefilling_elapsed_millis=1.302s
prefilling_size=706803 prefilling_elapsed_millis=1.402s
prefilling_size=731298 prefilling_elapsed_millis=1.502s
prefilling_size=753720 prefilling_elapsed_millis=1.602s
prefilling_size=774066 prefilling_elapsed_millis=1.702s
prefilling_size=793175 prefilling_elapsed_millis=1.803s
prefilling_size=810607 prefilling_elapsed_millis=1.903s
prefilling_size=826331 prefilling_elapsed_millis=2.003s
prefilling_size=840691 prefilling_elapsed_millis=2.103s
prefilling_size=854602 prefilling_elapsed_millis=2.203s
prefilling_size=866392 prefilling_elapsed_millis=2.304s
prefilling_size=878230 prefilling_elapsed_millis=2.404s
prefilling_size=889198 prefilling_elapsed_millis=2.504s
prefilling_size=898268 prefilling_elapsed_millis=2.604s
prefilling_size=906423 prefilling_elapsed_millis=2.704s
prefilling_size=914474 prefilling_elapsed_millis=2.805s
prefilling_size=922011 prefilling_elapsed_millis=2.905s
prefilling_size=928678 prefilling_elapsed_millis=3.005s
prefilling_size=934379 prefilling_elapsed_millis=3.105s
prefilling_size=939915 prefilling_elapsed_millis=3.205s
prefilling_size=944833 prefilling_elapsed_millis=3.306s
prefilling_size=949373 prefilling_elapsed_millis=3.406s
prefilling_size=953597 prefilling_elapsed_millis=3.506s
prefilling_size=956899 prefilling_elapsed_millis=3.606s
prefilling_size=960664 prefilling_elapsed_millis=3.706s
prefilling_size=963858 prefilling_elapsed_millis=3.807s
prefilling_size=966578 prefilling_elapsed_millis=3.907s
prefilling_size=968999 prefilling_elapsed_millis=4.007s
prefilling_size=972174 prefilling_elapsed_millis=4.107s
prefilling_size=974561 prefilling_elapsed_millis=4.207s
prefilling_size=976750 prefilling_elapsed_millis=4.308s
prefilling_size=979025 prefilling_elapsed_millis=4.408s
prefilling_size=981056 prefilling_elapsed_millis=4.508s
finished prefilling to size 981055 for expected size 1000000 keysum=981203432992, performing 7935061 updates; total_prefilling_elapsed_ms=4609 ms)
pref_size=981055
pref_millis=4609
prefill_elapsed_ms=4634
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6998411.161985790
REALTIME_END_PERF_FORMAT=6998421.162128520
###############################################################################
################################## 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 3146 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.065s
tree_stats_numInternalsAtDepth=1 1 8 58 413 3146 24608 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 190849
tree_stats_numNodesAtDepth=1 1 8 58 413 3146 24608 190849
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 999600
tree_stats_avgDegreeAtDepth=1 8 7.25 7.12069 7.61743 7.822 7.75557 5.23765
tree_stats_height=8
tree_stats_numInternals=28235
tree_stats_numLeaves=190849
tree_stats_numNodes=219084
tree_stats_numKeys=999600
tree_stats_avgDegreeInternal=7.75931
tree_stats_avgDegreeLeaves=5.23765
tree_stats_avgDegree=5.56263
tree_stats_avgKeyDepth=7
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0
[2^00, 2^01]: 0
sum_limbo_reclamation_event_size_total=
count_limbo_reclamation_event_size_by_thread=
count_limbo_reclamation_event_size_total=
sum_limbo_reclamation_event_count_total=556366
first_thread_announced_epoch_by_thread=24014 24014 24014 24014 24014 24012 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24012 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014 24014
sum_commit_tx_reader_total=
sum_commit_tx_reader_by_thread=
sum_commit_tx_writer_total=
sum_commit_tx_writer_by_thread=
sum_abort_tx_total=
sum_abort_tx_by_thread=
max_ht_capacity_by_thread=
linear_histogram_of_none_tx_rdset_size_full_data=
linear_histogram_of_none_tx_wrset_size_full_data=
sum_fasthtm_commit_total=
sum_fasthtm_abort_total=
sum_htmpostfix_commit_total=
sum_htmpostfix_abort_total=
sum_htmprefix_commit_total=
sum_htmprefix_abort_total=
sum_htmprefix_abort_explicit_total=
sum_htmprefix_abort_conflict_total=
sum_htmprefix_abort_capacity_total=
sum_htmprefix_abort_nesting_total=
sum_htmprefix_abort_illegal_total=
sum_htmprefix_abort_zero_total=
sum_stmprefix_abort_total=
sum_stmprefix_commit_total=
sum_slow_commit_total=
sum_abtree_insert_repeat_total=
sum_abtree_erase_repeat_total=
sum_abtree_search_repeat_total=
sum_abtree_search_complete_total=
sum_abtree_fix_weight_viol_attempt_total=
sum_abtree_fix_degree_viol_attempt_total=
sum_abtree_rebalancing_kcas_attempt_total=
sum_abtree_update_kcas_attempt_total=
sum_abtree_internal_complex_search_while_iters_total=
sum_abtree_internal_basic_search_while_iters_total=
sum_kcas_desc_new_total=
sum_num_inserts_by_thread=185157 186920 186635 185252 183446 183366 187029 188904 186898 186837 186256 185077 187108 186728 186500 186249 184400 184004 187268 187517 191481 188869 184822 185506 190176 193343 192181 191263 188039 188618 192009 193035 193419 193275 191597 190291 193715 193258 190987 191768 191119 189193 194887 195344 197491 196294 192649 191743
sum_num_inserts_total=9087923
sum_num_deletes_by_thread=184503 187334 185081 185541 183784 183413 186752 188394 187551 187038 185851 185506 186372 187301 186930 186196 183847 183965 187991 186702 191295 187523 184388 184164 189302 192670 192595 190894 187844 188548 192557 193330 193600 193514 191804 190541 193202 193543 191847 193684 190986 189175 195182 194619 198775 195531 192384 192095
sum_num_deletes_total=9085644
sum_num_searches_by_thread=3325744 3359635 3334485 3338888 3314612 3297625 3356847 3392996 3370348 3362976 3352468 3337323 3366225 3360920 3359351 3348362 3310784 3306256 3377579 3369029 3437719 3395233 3326928 3326679 3427135 3474883 3463674 3437920 3394516 3398931 3454841 3469708 3476688 3484639 3453809 3429591 3492471 3481381 3449763 3471907 3437697 3403658 3505409 3505307 3563178 3531306 3468395 3458491
sum_num_searches_total=163564310
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3695404 3733889 3706201 3709681 3681842 3664404 3730628 3770294 3744797 3736851 3724575 3707906 3739705 3734949 3732781 3720807 3679031 3674225 3752838 3743248 3820495 3771625 3696138 3696349 3806613 3860896 3848450 3820077 3770399 3776097 3839407 3856073 3863707 3871428 3837210 3810423 3879388 3868182 3832597 3857359 3819802 3782026 3895478 3895270 3959444 3923131 3853428 3842329
average_num_operations_total=3786205
stdev_num_operations_total=10899
sum_num_operations_total=181737877
min_num_operations_total=3664404
max_num_operations_total=3959444
first_time_thread_terminate_by_thread=10000165 10000145 10000141 10000152 10000156 10000148 10000142 10000145 10000162 10000161 10000159 10000155 10000151 10000163 10000152 10000157 10000140 10000143 10000141 10000140 10000160 10000158 10000146 10000163 10000141 10000141 10000141 10000140 10000141 10000140 10000154 10000141 10000160 10000141 10000141 10000142 10000151 10000140 10000150 10000145 10000161 10000164 10000165 10000150 10000141 10000141 10000154 10000146
min_time_thread_terminate_total=10000140
max_time_thread_terminate_total=10000165
first_time_thread_start_by_thread=4 5 4 4 4 5 5 5 4 5 5 5 4 4 4 5 4 5 4 4 5 4 4 4 4 5 4 4 4 5 5 5 4 5 5 5 4 4 4 5 4 5 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=998937775065
threads_final_size=999600
final_keysum=998937775065
final_size=999600
validate_result=success
Validation OK.
Structural validation OK.
total_find=163564310
total_rq=0
total_inserts=9087923
total_deletes=9085644
total_updates=18173567
total_queries=163564310
total_ops=181737877
find_throughput=16356431
rq_throughput=0
update_throughput=1817356
query_throughput=16356431
total_throughput=18173787
total find : 163564310
total rq : 0
total inserts : 9087923
total deletes : 9085644
total updates : 18173567
total queries : 163564310
total ops : 181737877
find throughput : 16356431
rq throughput : 0
update throughput : 1817356
query throughput : 16356431
total throughput : 18173787
elapsed milliseconds : 10000
napping milliseconds overtime : 0
PAPI_L2_TCM=16.4056
PAPI_L3_TCM=1.86716
PAPI_TOT_CYC=7314.71
PAPI_TOT_INS=2538.94
garbage=79869226296060
total_execution_walltime=14.992s
REALTIME_MAIN_END_PERF_FORMAT=6998421.333504629
Hybrid noREC system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 184854351
[slow htm]
[fallback]
total fallback commit : 4818588
seconds global lock is held : 0.414031
[time_cmd_output] time_elapsed_sec=15.15, faults_major=0, faults_minor=138720, mem_maxresident_kb=556000, user_cputime=733.68, sys_cputime=3.90, percent_cpu=4866%
timeout=false
algorithm=brown_abtree_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