__step=000018
__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.rhnorec_post -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=data000018.txt
__path_data=data/data000018.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6991747.928132296
binary=./brown_int_bst_tm_auto.debra.rhnorec_post
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


RH noREC w/postfix htm system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.006s
prefilling_size=454474 prefilling_elapsed_millis=1.001s
prefilling_size=498039 prefilling_elapsed_millis=1.101s
prefilling_size=541250 prefilling_elapsed_millis=1.201s
prefilling_size=583757 prefilling_elapsed_millis=1.301s
prefilling_size=626081 prefilling_elapsed_millis=1.402s
prefilling_size=668204 prefilling_elapsed_millis=1.502s
prefilling_size=710169 prefilling_elapsed_millis=1.602s
prefilling_size=751781 prefilling_elapsed_millis=1.702s
prefilling_size=793167 prefilling_elapsed_millis=1.802s
prefilling_size=834312 prefilling_elapsed_millis=1.903s
prefilling_size=875320 prefilling_elapsed_millis=2.003s
prefilling_size=916117 prefilling_elapsed_millis=2.103s
prefilling_size=956648 prefilling_elapsed_millis=2.203s
prefilling_size=997072 prefilling_elapsed_millis=2.303s
prefilling_size=1036961 prefilling_elapsed_millis=2.404s
prefilling_size=1076976 prefilling_elapsed_millis=2.504s
prefilling_size=1116560 prefilling_elapsed_millis=2.604s
prefilling_size=1155779 prefilling_elapsed_millis=2.704s
prefilling_size=1194826 prefilling_elapsed_millis=2.804s
prefilling_size=1233615 prefilling_elapsed_millis=2.905s
prefilling_size=1272156 prefilling_elapsed_millis=3.005s
prefilling_size=1310645 prefilling_elapsed_millis=3.105s
prefilling_size=1349019 prefilling_elapsed_millis=3.205s
prefilling_size=1386990 prefilling_elapsed_millis=3.305s
prefilling_size=1424698 prefilling_elapsed_millis=3.406s
prefilling_size=1462202 prefilling_elapsed_millis=3.506s
prefilling_size=1499556 prefilling_elapsed_millis=3.606s
prefilling_size=1536586 prefilling_elapsed_millis=3.706s
prefilling_size=1573624 prefilling_elapsed_millis=3.806s
prefilling_size=1610621 prefilling_elapsed_millis=3.907s
prefilling_size=1647137 prefilling_elapsed_millis=4.007s
prefilling_size=1683488 prefilling_elapsed_millis=4.107s
prefilling_size=1719884 prefilling_elapsed_millis=4.207s
prefilling_size=1756010 prefilling_elapsed_millis=4.307s
prefilling_size=1791780 prefilling_elapsed_millis=4.408s
prefilling_size=1827385 prefilling_elapsed_millis=4.508s
prefilling_size=1862902 prefilling_elapsed_millis=4.608s
prefilling_size=1898130 prefilling_elapsed_millis=4.708s
prefilling_size=1933167 prefilling_elapsed_millis=4.808s
prefilling_size=1968111 prefilling_elapsed_millis=4.909s
prefilling_size=2002937 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 7997008 keys with 48 threads...
tid=0 thread_prefill_amount=45826 percent_done_estimate=27.5 elapsed_s=5 est_remaining_s=32 / 37
tid=0 thread_prefill_amount=49992 percent_done_estimate=30.0 elapsed_s=6 est_remaining_s=30 / 36
tid=0 thread_prefill_amount=54158 percent_done_estimate=32.5 elapsed_s=6 est_remaining_s=29 / 35
tid=0 thread_prefill_amount=58324 percent_done_estimate=35.0 elapsed_s=7 est_remaining_s=28 / 34
tid=0 thread_prefill_amount=62490 percent_done_estimate=37.5 elapsed_s=7 est_remaining_s=26 / 33
tid=0 thread_prefill_amount=66656 percent_done_estimate=40.0 elapsed_s=7 est_remaining_s=25 / 33
tid=0 thread_prefill_amount=70822 percent_done_estimate=42.5 elapsed_s=8 est_remaining_s=24 / 32
tid=0 thread_prefill_amount=74988 percent_done_estimate=45.0 elapsed_s=8 est_remaining_s=23 / 31
tid=0 thread_prefill_amount=79154 percent_done_estimate=47.5 elapsed_s=9 est_remaining_s=22 / 30
tid=0 thread_prefill_amount=83320 percent_done_estimate=50.0 elapsed_s=9 est_remaining_s=21 / 30
tid=0 thread_prefill_amount=87486 percent_done_estimate=52.5 elapsed_s=10 est_remaining_s=19 / 29
tid=0 thread_prefill_amount=91652 percent_done_estimate=55.0 elapsed_s=10 est_remaining_s=18 / 28
tid=0 thread_prefill_amount=95818 percent_done_estimate=57.5 elapsed_s=10 est_remaining_s=17 / 28
tid=0 thread_prefill_amount=99984 percent_done_estimate=60.0 elapsed_s=11 est_remaining_s=16 / 27
tid=0 thread_prefill_amount=104150 percent_done_estimate=62.5 elapsed_s=11 est_remaining_s=15 / 26
tid=0 thread_prefill_amount=108316 percent_done_estimate=65.0 elapsed_s=12 est_remaining_s=14 / 26
tid=0 thread_prefill_amount=112482 percent_done_estimate=67.5 elapsed_s=12 est_remaining_s=13 / 25
tid=0 thread_prefill_amount=116648 percent_done_estimate=70.0 elapsed_s=13 est_remaining_s=12 / 25
tid=0 thread_prefill_amount=120814 percent_done_estimate=72.5 elapsed_s=13 est_remaining_s=11 / 24
tid=0 thread_prefill_amount=124980 percent_done_estimate=75.0 elapsed_s=13 est_remaining_s=10 / 23
tid=0 thread_prefill_amount=129146 percent_done_estimate=77.5 elapsed_s=14 est_remaining_s=9 / 23
tid=0 thread_prefill_amount=133312 percent_done_estimate=80.0 elapsed_s=14 est_remaining_s=8 / 22
tid=0 thread_prefill_amount=137478 percent_done_estimate=82.5 elapsed_s=15 est_remaining_s=7 / 22
tid=0 thread_prefill_amount=141644 percent_done_estimate=85.0 elapsed_s=15 est_remaining_s=6 / 21
tid=0 thread_prefill_amount=145810 percent_done_estimate=87.5 elapsed_s=16 est_remaining_s=5 / 20
tid=0 thread_prefill_amount=149976 percent_done_estimate=90.0 elapsed_s=16 est_remaining_s=4 / 20
tid=0 thread_prefill_amount=154142 percent_done_estimate=92.5 elapsed_s=16 est_remaining_s=3 / 19
tid=0 thread_prefill_amount=158308 percent_done_estimate=95.0 elapsed_s=17 est_remaining_s=2 / 19
tid=0 thread_prefill_amount=162474 percent_done_estimate=97.5 elapsed_s=17 est_remaining_s=1 / 18
tid=0 thread_prefill_amount=166640 percent_done_estimate=100.0 elapsed_s=18 est_remaining_s=-0 / 18
tid=0 thread_prefill_amount=170806 percent_done_estimate=102.5 elapsed_s=18 est_remaining_s=-1 / 17
tid=0 thread_prefill_amount=174972 percent_done_estimate=105.0 elapsed_s=19 est_remaining_s=-2 / 17
tid=0 thread_prefill_amount=179138 percent_done_estimate=107.5 elapsed_s=19 est_remaining_s=-3 / 16
tid=0 thread_prefill_amount=183304 percent_done_estimate=110.0 elapsed_s=19 est_remaining_s=-4 / 15
tid=0 thread_prefill_amount=187470 percent_done_estimate=112.5 elapsed_s=20 est_remaining_s=-5 / 15
tid=0 thread_prefill_amount=191636 percent_done_estimate=115.0 elapsed_s=20 est_remaining_s=-6 / 14
tid=0 thread_prefill_amount=195802 percent_done_estimate=117.5 elapsed_s=21 est_remaining_s=-7 / 14
tid=0 thread_prefill_amount=199968 percent_done_estimate=120.0 elapsed_s=21 est_remaining_s=-8 / 13
tid=0 thread_prefill_amount=204134 percent_done_estimate=122.5 elapsed_s=22 est_remaining_s=-9 / 13
tid=0 thread_prefill_amount=208300 percent_done_estimate=125.0 elapsed_s=22 est_remaining_s=-10 / 12
timing_elapsed 17.855s
prefilling_size=10000000 prefilling_elapsed_millis=17.897s
finished prefilling to size 10000000 for expected size 10000000 keysum=100008019632616, performing 16288592 updates; total_prefilling_elapsed_ms=17897 ms)
pref_size=10000000
pref_millis=17897
prefill_elapsed_ms=17898
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=878

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6991771.152155206
REALTIME_END_PERF_FORMAT=6991781.152230515

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

tree_stats_computeWalltime=0.181s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 501 973 1853 3440 6167 10637 17753 28686 44789 67023 96804 134641 180247 232223 288443 345442 398208 442610 474687 490897 490706 472706 440780 396738 345813 291340 236497 185944 140598 102771 72649 49846 33337 21856 13897 8644 5001 2868 1687 936 499 262 137 78 53 28 18 7 4 3 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 8 18 53 139 394 917 1874 3582 6560 11811 19519 30890 46747 66884 91716 120599 152144 184235 213333 237724 254353 263505 260823 250250 230596 206200 178275 147537 118708 91655 68394 48909 33939 22949 15202 9719 6321 3668 2071 1224 688 386 199 101 55 44 18 14 6 3 4 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 509 991 1906 3579 6561 11554 19627 32268 51349 78834 116323 165531 226994 299107 380159 466041 550352 626845 688020 728621 745059 736211 701603 646988 576409 497540 414772 333481 259306 194426 141043 98755 67276 44805 29099 18363 11322 6536 3758 2160 1187 648 336 179 108 72 36 21 10 6 6 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 509 991 1906 3579 6561 11554 19627 32268 51349 78834 116323 165531 226994 299107 380159 466041 550352 626845 688020 728621 745059 736211 701603 646988 576409 497540 414772 333481 259306 194426 141043 98755 67276 44805 29099 18363 11322 6536 3758 2160 1187 648 336 179 108 72 36 21 10 6 6 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99219 1.96267 1.94147 1.90556 1.87203 1.82106 1.77809 1.73954 1.70234 1.66301 1.62536 1.59083 1.55792 1.52363 1.49459 1.46717 1.43968 1.41544 1.3915 1.36908 1.34883 1.32951 1.31091 1.29391 1.2777 1.26323 1.24808 1.23382 1.21999 1.20758 1.19685 1.18509 1.1765 1.17046 1.16166 1.15348 1.14584 1.13558 1.13617 1.12586 1.1162 1.12553 1.1142 1.125 1.1676 1.17593 1.11111 1.08333 1.14286 1.2 1.5 1 1

tree_stats_height=61
tree_stats_numInternals=6582239
tree_stats_numLeaves=3404966
tree_stats_numNodes=9987205
tree_stats_numKeys=9987205

tree_stats_avgDegreeInternal=1.5173
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34093
tree_stats_avgKeyDepth=29.2598

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:64 2:1477 4:52399 8:363081 16:55687
    [2^00, 2^01]: 64
    (2^01, 2^02]: 1477
    (2^02, 2^03]: 52399
    (2^03, 2^04]: 363081
    (2^04, 2^05]: 55687
sum_limbo_reclamation_event_size_total=5416372
count_limbo_reclamation_event_size_by_thread=9848 9848 9848 9848 9849 9848 9849 9848 9848 9848 9848 9848 9848 9848 9848 9849 9848 9847 9848 9848 9849 9848 9848 9848 9848 9848 9848 9848 9849 9848 9848 9848 9848 9848 9847 9848 9849 9848 9848 9849 9847 9848 9849 9848 9848 9848 9847 9848
count_limbo_reclamation_event_size_total=472708
sum_limbo_reclamation_event_count_total=472895
first_thread_announced_epoch_by_thread=21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458 21458
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=216484194
sum_fasthtm_abort_total=8475124
sum_htmpostfix_commit_total=1353
sum_htmpostfix_abort_total=37872
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=1742
sum_stmprefix_commit_total=3
sum_slow_commit_total=946
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=222515 225506 227239 225380 226274 223105 224905 223569 229171 226053 225296 225284 224064 226539 225263 225958 224795 223199 225331 224608 226960 225101 224426 225288 224038 225113 227049 225323 226513 225692 224053 224318 225997 226285 225161 223550 225115 225139 227782 228317 225687 226665 223479 226149 226577 227171 226379 224932
sum_num_inserts_total=10822313
sum_num_deletes_by_thread=222508 225760 226217 225139 226107 224515 225368 223667 227651 226769 224805 226491 223490 226810 225281 225911 225146 223351 224617 224826 227085 224303 224421 225038 224583 225834 227240 224976 226370 224943 223109 224007 225710 225478 225419 223587 226373 223872 227846 227963 225964 226040 222821 225453 228023 227620 226993 223932
sum_num_deletes_total=10819432
sum_num_searches_by_thread=3998930 4056916 4093175 4052995 4079386 4033627 4058042 4029969 4118021 4080681 4051923 4071463 4020571 4079523 4058726 4062669 4059891 4031925 4043956 4049947 4089435 4048521 4039287 4049968 4044746 4057901 4096341 4056760 4087279 4048522 4021440 4032925 4063439 4070997 4045184 4016202 4062219 4036041 4095905 4107387 4056555 4071052 4007410 4067917 4099288 4105121 4080488 4054085
sum_num_searches_total=194844751
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4443953 4508182 4546631 4503514 4531767 4481247 4508315 4477205 4574843 4533503 4502024 4523238 4468125 4532872 4509270 4514538 4509832 4478475 4493904 4499381 4543480 4497925 4488134 4500294 4493367 4508848 4550630 4507059 4540162 4499157 4468602 4481250 4515146 4522760 4495764 4463339 4513707 4485052 4551533 4563667 4508206 4523757 4453710 4519519 4553888 4559912 4533860 4502949
average_num_operations_total=4510135
stdev_num_operations_total=4278
sum_num_operations_total=216486496
min_num_operations_total=4443953
max_num_operations_total=4574843
first_time_thread_terminate_by_thread=10000133 10000076 10000075 10000074 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000074 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000075 10000076 10000075 10000075 10000075 10000075 10000074 10000075 10000075 10000075 10000076 10000075 10000075 10000075 10000075 10000075 10000075
min_time_thread_terminate_total=10000074
max_time_thread_terminate_total=10000133
first_time_thread_start_by_thread=9 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=9
sum_duration_all_ops_total=

threads_final_keysum=99868892646422
threads_final_size=9987205
final_keysum=99868892646422
final_size=9987205
validate_result=success
Validation OK.
Structural validation OK.

total_find=194844751
total_rq=0
total_inserts=10822313
total_deletes=10819432
total_updates=21641745
total_queries=194844751
total_ops=216486496
find_throughput=19484475
rq_throughput=0
update_throughput=2164174
query_throughput=19484475
total_throughput=21648649


total find                    : 194844751
total rq                      : 0
total inserts                 : 10822313
total deletes                 : 10819432
total updates                 : 21641745
total queries                 : 194844751
total ops                     : 216486496
find throughput               : 19484475
rq throughput                 : 0
update throughput             : 2164174
query throughput              : 19484475
total throughput              : 21648649

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=22.0893
PAPI_L3_TCM=7.0843
PAPI_TOT_CYC=6183.87
PAPI_TOT_INS=1341.68
garbage=975460221882746
total_execution_walltime=33.567s
REALTIME_MAIN_END_PERF_FORMAT=6991781.506870111
RH noREC w/postfix htm system shutdown:
    HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=33.76, faults_major=0, faults_minor=251501, mem_maxresident_kb=1006704, user_cputime=1547.14, sys_cputime=39.00, percent_cpu=4697%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.rhnorec_post
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2