__step=000036
__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.hytm1 -nwork 96 -nprefill 96 -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=data000036.txt
__path_data=data/data000036.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992335.224830539
binary=./brown_int_bst_tm_auto.debra.hytm1
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=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143


HyTM1 system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.04s
prefilling_size=1947523 prefilling_elapsed_millis=1s
prefilling_size=2141521 prefilling_elapsed_millis=1.1s
prefilling_size=2341061 prefilling_elapsed_millis=1.2s
prefilling_size=2527885 prefilling_elapsed_millis=1.301s
prefilling_size=2713714 prefilling_elapsed_millis=1.401s
prefilling_size=2896492 prefilling_elapsed_millis=1.501s
prefilling_size=3092012 prefilling_elapsed_millis=1.601s
prefilling_size=3272504 prefilling_elapsed_millis=1.701s
prefilling_size=3446170 prefilling_elapsed_millis=1.801s
prefilling_size=3629112 prefilling_elapsed_millis=1.901s
prefilling_size=3795693 prefilling_elapsed_millis=2.002s
prefilling_size=3956861 prefilling_elapsed_millis=2.102s
prefilling_size=4119562 prefilling_elapsed_millis=2.202s
prefilling_size=4283231 prefilling_elapsed_millis=2.302s
prefilling_size=4443585 prefilling_elapsed_millis=2.402s
prefilling_size=4607203 prefilling_elapsed_millis=2.502s
prefilling_size=4775449 prefilling_elapsed_millis=2.603s
prefilling_size=4987815 prefilling_elapsed_millis=2.703s
prefilling_size=5153196 prefilling_elapsed_millis=2.803s
prefilling_size=5304015 prefilling_elapsed_millis=2.903s
prefilling_size=5458737 prefilling_elapsed_millis=3.003s
prefilling_size=5611830 prefilling_elapsed_millis=3.103s
prefilling_size=5762433 prefilling_elapsed_millis=3.204s
prefilling_size=5910407 prefilling_elapsed_millis=3.304s
prefilling_size=6057652 prefilling_elapsed_millis=3.404s
prefilling_size=6204316 prefilling_elapsed_millis=3.504s
prefilling_size=6351209 prefilling_elapsed_millis=3.604s
prefilling_size=6500063 prefilling_elapsed_millis=3.704s
prefilling_size=6638259 prefilling_elapsed_millis=3.804s
prefilling_size=6780015 prefilling_elapsed_millis=3.905s
prefilling_size=6916254 prefilling_elapsed_millis=4.005s
prefilling_size=7050701 prefilling_elapsed_millis=4.105s
prefilling_size=7184967 prefilling_elapsed_millis=4.205s
prefilling_size=7317012 prefilling_elapsed_millis=4.305s
prefilling_size=7444363 prefilling_elapsed_millis=4.405s
prefilling_size=7573012 prefilling_elapsed_millis=4.506s
prefilling_size=7695995 prefilling_elapsed_millis=4.606s
prefilling_size=7814426 prefilling_elapsed_millis=4.706s
prefilling_size=7931898 prefilling_elapsed_millis=4.806s
prefilling_size=8046960 prefilling_elapsed_millis=4.906s
prefilling_size=8157780 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 1842066 keys with 96 threads...
tid=0 thread_prefill_amount=87486 percent_done_estimate=455.9 elapsed_s=5 est_remaining_s=-9 / -4
tid=0 thread_prefill_amount=89569 percent_done_estimate=466.8 elapsed_s=5 est_remaining_s=-9 / -4
tid=0 thread_prefill_amount=91652 percent_done_estimate=477.6 elapsed_s=5 est_remaining_s=-10 / -4
tid=0 thread_prefill_amount=93735 percent_done_estimate=488.5 elapsed_s=6 est_remaining_s=-10 / -4
tid=0 thread_prefill_amount=95818 percent_done_estimate=499.4 elapsed_s=6 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=97901 percent_done_estimate=510.2 elapsed_s=6 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=99984 percent_done_estimate=521.1 elapsed_s=6 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=102067 percent_done_estimate=531.9 elapsed_s=6 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=104150 percent_done_estimate=542.8 elapsed_s=6 est_remaining_s=-11 / -5
timing_elapsed 0.870006s
prefilling_size=10000000 prefilling_elapsed_millis=0.872s
finished prefilling to size 10000000 for expected size 10000000 keysum=99999342803391, performing 37195884 updates; total_prefilling_elapsed_ms=872 ms)
pref_size=10000000
pref_millis=872
prefill_elapsed_ms=872
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=431

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6992341.432163495
REALTIME_END_PERF_FORMAT=6992351.432241141

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

tree_stats_computeWalltime=0.085s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 250 495 967 1857 3486 6420 11429 19674 32422 51149 77033 110919 152994 202204 256720 313682 367788 417081 454559 476729 484445 475151 451786 415541 370273 320085 269547 219067 173020 131535 96878 69046 47203 31021 19855 12120 7085 4005 2215 1243 638 336 162 88 51 18 5 3 1 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 2 2 9 41 129 305 800 1708 3753 7348 13482 23274 36807 55333 78645 106044 137499 168315 200461 227706 247388 259530 261672 255696 240623 219624 193155 167105 137944 111316 86439 64560 46926 32839 21805 14197 8836 5174 3003 1628 959 466 257 119 64 44 17 3 2 1 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 252 497 976 1898 3615 6725 12229 21382 36175 58497 90515 134193 189801 257537 335365 419726 505287 585396 655020 704435 731833 734681 713458 671237 610896 539709 462702 386172 310964 242851 183317 133606 94129 63860 41660 26317 15921 9179 5218 2871 1597 802 419 207 115 62 22 6 3 2 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 252 497 976 1898 3615 6725 12229 21382 36175 58497 90515 134193 189801 257537 335365 419726 505287 585396 655020 704435 731833 734681 713458 671237 610896 539709 462702 386172 310964 242851 183317 133606 94129 63860 41660 26317 15921 9179 5218 2871 1597 802 419 207 115 62 22 6 3 2 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.97656 1.98016 1.96781 1.95389 1.92624 1.89599 1.86379 1.81389 1.77172 1.7208 1.67296 1.6315 1.58782 1.5508 1.51706 1.48606 1.4565 1.43066 1.40646 1.38148 1.36214 1.34193 1.32437 1.30759 1.29104 1.27736 1.26425 1.25205 1.23797 1.22456 1.21323 1.20035 1.18774 1.17696 1.1666 1.15511 1.14443 1.13152 1.13215 1.12572 1.1233 1.10269 1.10349 1.1074 1.13043 1.09565 1.06452 1.04545 1 1.33333 1 1

tree_stats_height=59
tree_stats_numInternals=6560536
tree_stats_numLeaves=3443057
tree_stats_numNodes=10003593
tree_stats_numKeys=10003593

tree_stats_avgDegreeInternal=1.52481
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34418
tree_stats_avgKeyDepth=28.9243

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:9 2:31 4:189 8:27979 16:397804 32:16111 64:45
    [2^00, 2^01]: 9
    (2^01, 2^02]: 31
    (2^02, 2^03]: 189
    (2^03, 2^04]: 27979
    (2^04, 2^05]: 397804
    (2^05, 2^06]: 16111
    (2^06, 2^07]: 45
sum_limbo_reclamation_event_size_total=10007249
count_limbo_reclamation_event_size_by_thread=4606 4606 4606 4606 4606 4606 4605 4606 4606 4605 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4605 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4605 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4605 4606 4606 4605 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4606 4605 4606 4606 4606 4605
count_limbo_reclamation_event_size_total=442168
sum_limbo_reclamation_event_count_total=442462
first_thread_announced_epoch_by_thread=10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078 10078
sum_num_inserts_by_thread=204942 212982 214726 215230 204956 209504 211989 206076 208085 206704 211787 208602 213887 213618 214219 211025 211451 210756 205244 209056 213623 206505 205933 211478 203194 211942 211583 213998 208271 202609 205500 214136 209306 212281 210902 208429 213645 206120 209257 211183 210135 211182 208609 210257 212889 207802 212341 204943 182205 207596 213174 211630 214371 214559 206548 205634 215377 218564 211638 212145 210862 210702 216440 209767 210132 208793 211065 171017 214240 209563 212816 198914 139351 213516 212026 213626 216161 213183 209905 212295 216615 215472 208973 209086 206040 204190 216321 215451 208442 201785 213001 174646 215818 200260 214523 212625
sum_num_inserts_total=20033955
sum_num_deletes_by_thread=204409 212727 214424 213393 205460 210702 211733 206169 207779 206996 211962 207577 213503 213984 215462 211248 210143 212412 205559 208790 212614 206716 206010 211351 205126 211577 212187 213019 208762 201073 205164 213955 209549 211154 211169 208148 212925 206266 208872 210956 210749 211758 208412 210029 212896 207395 212234 204870 181645 207189 212475 212363 214022 214888 205426 205937 215455 218442 212417 212595 211207 210571 215920 209324 210192 209069 211675 170943 214639 209309 213587 199648 139668 214898 212089 213305 216164 212699 209074 211601 216230 214715 209494 208864 206481 203104 215724 214547 208561 201599 211730 174984 215049 199854 215285 213200
sum_num_deletes_total=20027225
sum_num_searches_by_thread=3672804 3829329 3868651 3851244 3690165 3771830 3803994 3719768 3746837 3730379 3810136 3751169 3847820 3843104 3870700 3797417 3789336 3819358 3696856 3765897 3836509 3726942 3709591 3805292 3671434 3819653 3813480 3846413 3759210 3632699 3706686 3845064 3775826 3804397 3806407 3754055 3840578 3714519 3768278 3804936 3790354 3799894 3754524 3784270 3842709 3743786 3821660 3686407 3278133 3747342 3824781 3817893 3854843 3863717 3706515 3698440 3881893 3926399 3826043 3812747 3793978 3800119 3896681 3765825 3791135 3755687 3813863 3083681 3861300 3775469 3841034 3593932 2515809 3854118 3820188 3841323 3889844 3831887 3766894 3828262 3891556 3879656 3765511 3768304 3714815 3668709 3881398 3870764 3759626 3629507 3830876 3149879 3876460 3601479 3863539 3827698
sum_num_searches_total=360681919
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4082155 4255038 4297801 4279867 4100581 4192036 4227716 4132013 4162701 4144079 4233885 4167348 4275210 4270706 4300381 4219690 4210930 4242526 4107659 4183743 4262746 4140163 4121534 4228121 4079754 4243172 4237250 4273430 4176243 4036381 4117350 4273155 4194681 4227832 4228478 4170632 4267148 4126905 4186407 4227075 4211238 4222834 4171545 4204556 4268494 4158983 4246235 4096220 3641983 4162127 4250430 4241886 4283236 4293164 4118489 4110011 4312725 4363405 4250098 4237487 4216047 4221392 4329041 4184916 4211459 4173549 4236603 3425641 4290179 4194341 4267437 3992494 2794828 4282532 4244303 4268254 4322169 4257769 4185873 4252158 4324401 4309843 4183978 4186254 4127336 4076003 4313443 4300762 4176629 4032891 4255607 3499509 4307327 4001593 4293347 4253523
average_num_operations_total=4174407
stdev_num_operations_total=20698
sum_num_operations_total=400743099
min_num_operations_total=2794828
max_num_operations_total=4363405
first_time_thread_terminate_by_thread=10000140 10000078 10000082 10000080 10000082 10000081 10000081 10000082 10000081 10000078 10000080 10000082 10000082 10000082 10000082 10000081 10000078 10000081 10000081 10000078 10000080 10000082 10000082 10000082 10000079 10000082 10000082 10000080 10000082 10000081 10000078 10000082 10000081 10000079 10000080 10000082 10000082 10000082 10000082 10000081 10000078 10000080 10000078 10000081 10000080 10000082 10000082 10000082 10000080 10000080 10000080 10000078 10000080 10000079 10000081 10000081 10000079 10000081 10000080 10000078 10000079 10000080 10000078 10000080 10000080 10000081 10000079 10000079 10000081 10000080 10000079 10000081 10000080 10000080 10000078 10000080 10000080 10000078 10000081 10000081 10000080 10000079 10000080 10000080 10000079 10000080 10000078 10000080 10000080 10000081 10000078 10000077 10000078 10000080 10000079 10000081
min_time_thread_terminate_total=10000077
max_time_thread_terminate_total=10000140
first_time_thread_start_by_thread=11 5 5 5 6 6 6 5 5 6 6 6 5 6 6 5 6 5 5 6 6 5 5 5 5 5 5 5 6 6 6 5 5 6 6 6 5 6 6 5 6 5 5 6 6 5 5 5 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 5 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 5 6 6 6 6 6 6
min_time_thread_start_total=5
max_time_thread_start_total=11
sum_duration_all_ops_total=

threads_final_keysum=100023277930536
threads_final_size=10003593
final_keysum=100023277930536
final_size=10003593
validate_result=success
Validation OK.
Structural validation OK.

total_find=360681919
total_rq=0
total_inserts=20033955
total_deletes=20027225
total_updates=40061180
total_queries=360681919
total_ops=400743099
find_throughput=36068191
rq_throughput=0
update_throughput=4006118
query_throughput=36068191
total_throughput=40074309


total find                    : 360681919
total rq                      : 0
total inserts                 : 20033955
total deletes                 : 20027225
total updates                 : 40061180
total queries                 : 360681919
total ops                     : 400743099
find throughput               : 36068191
rq throughput                 : 0
update throughput             : 4006118
query throughput              : 36068191
total throughput              : 40074309

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=20.1429
PAPI_L3_TCM=6.46262
PAPI_TOT_CYC=6654.28
PAPI_TOT_INS=2529.29
garbage=1791384100705399
total_execution_walltime=16.439s
REALTIME_MAIN_END_PERF_FORMAT=6992351.665580575
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 437294816
[slow htm]
[fallback]
total fallback commit         : 644168
seconds global lock is held   : 5.42681
[time_cmd_output] time_elapsed_sec=16.60, faults_major=0, faults_minor=241778, mem_maxresident_kb=663784, user_cputime=1543.57, sys_cputime=6.16, percent_cpu=9332%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.hytm1
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2