__step=000035
__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=data000035.txt
__path_data=data/data000035.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992318.596132598
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.02s
prefilling_size=1933992 prefilling_elapsed_millis=1.001s
prefilling_size=2131291 prefilling_elapsed_millis=1.101s
prefilling_size=2332681 prefilling_elapsed_millis=1.201s
prefilling_size=2520857 prefilling_elapsed_millis=1.301s
prefilling_size=2703079 prefilling_elapsed_millis=1.402s
prefilling_size=2887953 prefilling_elapsed_millis=1.502s
prefilling_size=3094905 prefilling_elapsed_millis=1.602s
prefilling_size=3273661 prefilling_elapsed_millis=1.702s
prefilling_size=3446557 prefilling_elapsed_millis=1.802s
prefilling_size=3637905 prefilling_elapsed_millis=1.903s
prefilling_size=3815047 prefilling_elapsed_millis=2.003s
prefilling_size=3984337 prefilling_elapsed_millis=2.103s
prefilling_size=4157110 prefilling_elapsed_millis=2.203s
prefilling_size=4327179 prefilling_elapsed_millis=2.303s
prefilling_size=4494318 prefilling_elapsed_millis=2.404s
prefilling_size=4660200 prefilling_elapsed_millis=2.504s
prefilling_size=4855552 prefilling_elapsed_millis=2.604s
prefilling_size=5052546 prefilling_elapsed_millis=2.704s
prefilling_size=5216334 prefilling_elapsed_millis=2.804s
prefilling_size=5373689 prefilling_elapsed_millis=2.905s
prefilling_size=5521988 prefilling_elapsed_millis=3.005s
prefilling_size=5673500 prefilling_elapsed_millis=3.105s
prefilling_size=5828180 prefilling_elapsed_millis=3.205s
prefilling_size=5974301 prefilling_elapsed_millis=3.305s
prefilling_size=6120132 prefilling_elapsed_millis=3.406s
prefilling_size=6263114 prefilling_elapsed_millis=3.506s
prefilling_size=6407531 prefilling_elapsed_millis=3.606s
prefilling_size=6553548 prefilling_elapsed_millis=3.706s
prefilling_size=6691038 prefilling_elapsed_millis=3.806s
prefilling_size=6833678 prefilling_elapsed_millis=3.907s
prefilling_size=6970899 prefilling_elapsed_millis=4.007s
prefilling_size=7103103 prefilling_elapsed_millis=4.107s
prefilling_size=7237229 prefilling_elapsed_millis=4.207s
prefilling_size=7367008 prefilling_elapsed_millis=4.307s
prefilling_size=7494292 prefilling_elapsed_millis=4.408s
prefilling_size=7617465 prefilling_elapsed_millis=4.508s
prefilling_size=7737954 prefilling_elapsed_millis=4.608s
prefilling_size=7855393 prefilling_elapsed_millis=4.708s
prefilling_size=7969898 prefilling_elapsed_millis=4.808s
prefilling_size=8081739 prefilling_elapsed_millis=4.909s
prefilling_size=8192142 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 1807709 keys with 96 threads...
tid=0 thread_prefill_amount=85403 percent_done_estimate=453.5 elapsed_s=5 est_remaining_s=-9 / -4
tid=0 thread_prefill_amount=87486 percent_done_estimate=464.6 elapsed_s=5 est_remaining_s=-9 / -4
tid=0 thread_prefill_amount=89569 percent_done_estimate=475.7 elapsed_s=5 est_remaining_s=-10 / -4
tid=0 thread_prefill_amount=91652 percent_done_estimate=486.7 elapsed_s=5 est_remaining_s=-10 / -4
tid=0 thread_prefill_amount=93735 percent_done_estimate=497.8 elapsed_s=6 est_remaining_s=-10 / -4
tid=0 thread_prefill_amount=95818 percent_done_estimate=508.9 elapsed_s=6 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=97901 percent_done_estimate=519.9 elapsed_s=6 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=99984 percent_done_estimate=531.0 elapsed_s=6 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=102067 percent_done_estimate=542.0 elapsed_s=6 est_remaining_s=-11 / -5
timing_elapsed 0.86901s
prefilling_size=10000000 prefilling_elapsed_millis=0.871s
finished prefilling to size 10000000 for expected size 10000000 keysum=100008336743154, performing 37579658 updates; total_prefilling_elapsed_ms=871 ms)
pref_size=10000000
pref_millis=871
prefill_elapsed_ms=871
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=441

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6992324.788163560
REALTIME_END_PERF_FORMAT=6992334.788244757

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

tree_stats_computeWalltime=0.061s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 509 1008 1967 3752 6929 12417 21526 35731 56491 85835 124877 173153 229551 291852 354891 413821 464153 499563 516866 514461 491267 451252 398483 338977 278120 219692 168244 124863 89731 62714 42767 28482 18513 11880 7612 4788 3020 1884 1129 712 411 236 119 69 36 21 7 3 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 1 4 22 95 325 772 1787 4034 8228 14677 25138 41140 62663 89326 121923 157090 192403 227293 254456 273625 282696 278888 264341 239999 208832 175922 141228 110245 83019 60481 42841 29456 19919 12968 8291 5382 3325 2140 1359 801 519 296 195 89 57 24 18 6 4
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 510 1012 1989 3847 7254 13189 23313 39765 64719 100512 150015 214293 292214 381178 476814 570911 656556 726856 771322 788086 773963 730140 662824 578976 486952 395614 309472 235108 172750 123195 85608 57938 38432 24848 15903 10170 6345 4024 2488 1513 930 532 314 158 93 45 25 9 4
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 510 1012 1989 3847 7254 13189 23313 39765 64719 100512 150015 214293 292214 381178 476814 570911 656556 726856 771322 788086 773963 730140 662824 578976 486952 395614 309472 235108 172750 123195 85608 57938 38432 24848 15903 10170 6345 4024 2488 1513 930 532 314 158 93 45 25 9 4
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99609 1.98627 1.96937 1.9452 1.91032 1.86297 1.82614 1.78235 1.72898 1.68019 1.63853 1.59605 1.5556 1.51889 1.48524 1.45305 1.42517 1.40012 1.37388 1.35163 1.32928 1.30864 1.28977 1.27231 1.25558 1.24128 1.22694 1.21606 1.20368 1.19371 1.18584 1.17721 1.17174 1.16484 1.1619 1.16085 1.1531 1.15823 1.1501 1.15434 1.14408 1.13011 1.14662 1.1242 1.1519 1.09677 1.08889 1.08 1.11111 1

tree_stats_height=58
tree_stats_numInternals=6554895
tree_stats_numLeaves=3448344
tree_stats_numNodes=10003239
tree_stats_numKeys=10003239

tree_stats_avgDegreeInternal=1.52607
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34472
tree_stats_avgKeyDepth=28.2186

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:3 4:102 8:27016 16:406415 32:16992
    (2^01, 2^02]: 3
    (2^02, 2^03]: 102
    (2^03, 2^04]: 27016
    (2^04, 2^05]: 406415
    (2^05, 2^06]: 16992
sum_limbo_reclamation_event_size_total=10225672
count_limbo_reclamation_event_size_by_thread=4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693 4693
count_limbo_reclamation_event_size_total=450528
sum_limbo_reclamation_event_count_total=450815
first_thread_announced_epoch_by_thread=10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272 10272
sum_num_inserts_by_thread=215595 214512 218342 217475 218305 204796 210455 214281 211235 214410 216539 208654 214388 210743 210606 216388 217464 214471 211614 213831 216989 208595 213950 210240 213519 216363 218516 217113 216083 212573 211108 211939 216898 211360 216031 211961 211562 213845 215337 217892 216998 215475 214065 214397 217350 207983 216980 210416 212868 201322 217616 207382 216122 218250 213789 211484 217596 212172 218787 215762 216841 218233 221013 216836 219917 210158 207518 163133 219982 216426 218097 200348 218016 205466 218762 209023 218441 213971 217336 212628 220335 209412 214454 216117 216167 216016 216968 217360 215843 212494 213929 169248 220047 215653 220766 192154
sum_num_inserts_total=20461900
sum_num_deletes_by_thread=215601 215391 218459 217155 217407 203687 210314 214327 210600 215337 216410 209343 214847 210986 210948 216739 217498 214277 210782 213462 217403 208134 214149 209976 212720 216823 218097 217906 215481 212247 210551 211525 215429 211582 215194 213203 211319 214158 216558 217930 216737 216560 214325 214774 217668 207973 216546 210541 212222 201983 216658 205833 215412 217072 213727 210238 218554 212856 218445 215819 218243 218847 219919 217746 219617 210548 206837 162283 220341 215668 217617 200534 218006 204864 219165 207423 219224 214766 216919 211845 221170 210333 213783 215932 215165 217619 216433 217871 215757 213731 213366 168894 219412 214476 220603 191566
sum_num_deletes_total=20454421
sum_num_searches_by_thread=3884544 3866465 3931295 3907760 3928661 3666014 3790476 3854446 3793821 3858015 3891202 3761204 3859485 3793048 3810807 3898546 3914367 3851626 3805549 3837198 3909738 3750189 3853819 3774981 3833758 3909771 3936054 3924806 3882795 3826998 3802438 3810374 3900004 3807539 3883738 3821635 3813944 3851567 3888425 3919289 3909958 3886875 3851256 3862394 3906844 3738327 3905755 3781122 3833898 3634078 3908439 3722176 3878792 3915857 3841563 3788633 3927688 3819157 3931124 3890270 3910070 3928157 3970364 3904764 3954318 3788349 3722378 2923328 3981046 3892387 3918990 3602444 3931613 3688483 3948072 3740957 3939952 3864618 3910961 3819600 3981106 3773462 3852819 3899365 3876643 3905211 3903905 3930059 3886861 3825915 3855928 3040036 3963968 3869981 3966733 3460160
sum_num_searches_total=368273600
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4315740 4296368 4368096 4342390 4364373 4074497 4211245 4283054 4215656 4287762 4324151 4179201 4288720 4214777 4232361 4331673 4349329 4280374 4227945 4264491 4344130 4166918 4281918 4195197 4259997 4342957 4372667 4359825 4314359 4251818 4224097 4233838 4332331 4230481 4314963 4246799 4236825 4279570 4320320 4355111 4343693 4318910 4279646 4291565 4341862 4154283 4339281 4202079 4258988 4037383 4342713 4135391 4310326 4351179 4269079 4210355 4363838 4244185 4368356 4321851 4345154 4365237 4411296 4339346 4393852 4209055 4136733 3248744 4421369 4324481 4354704 4003326 4367635 4098813 4385999 4157403 4377617 4293355 4345216 4244073 4422611 4193207 4281056 4331414 4307975 4338846 4337306 4365290 4318461 4252140 4283223 3378178 4403427 4300110 4408102 3843880
average_num_operations_total=4262395
stdev_num_operations_total=17263
sum_num_operations_total=409189921
min_num_operations_total=3248744
max_num_operations_total=4422611
first_time_thread_terminate_by_thread=10000152 10000082 10000082 10000083 10000081 10000085 10000081 10000081 10000081 10000085 10000081 10000081 10000081 10000083 10000085 10000082 10000081 10000081 10000082 10000085 10000081 10000085 10000081 10000084 10000080 10000082 10000082 10000081 10000081 10000084 10000081 10000083 10000081 10000085 10000084 10000085 10000081 10000083 10000085 10000085 10000081 10000083 10000081 10000081 10000085 10000081 10000084 10000084 10000085 10000082 10000083 10000081 10000082 10000085 10000083 10000082 10000082 10000084 10000083 10000083 10000081 10000081 10000083 10000084 10000084 10000082 10000085 10000082 10000084 10000082 10000083 10000082 10000085 10000082 10000082 10000083 10000082 10000085 10000083 10000082 10000082 10000084 10000083 10000083 10000083 10000084 10000083 10000084 10000082 10000082 10000084 10000082 10000082 10000082 10000083 10000082
min_time_thread_terminate_total=10000080
max_time_thread_terminate_total=10000152
first_time_thread_start_by_thread=9 5 5 5 5 5 4 4 4 5 5 5 4 4 4 5 5 5 5 4 5 5 5 5 4 5 5 5 5 5 4 4 4 5 5 5 4 4 4 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 5 5 5 5 5 5 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=100040680200199
threads_final_size=10003239
final_keysum=100040680200199
final_size=10003239
validate_result=success
Validation OK.
Structural validation OK.

total_find=368273600
total_rq=0
total_inserts=20461900
total_deletes=20454421
total_updates=40916321
total_queries=368273600
total_ops=409189921
find_throughput=36827360
rq_throughput=0
update_throughput=4091632
query_throughput=36827360
total_throughput=40918992


total find                    : 368273600
total rq                      : 0
total inserts                 : 20461900
total deletes                 : 20454421
total updates                 : 40916321
total queries                 : 368273600
total ops                     : 409189921
find throughput               : 36827360
rq throughput                 : 0
update throughput             : 4091632
query throughput              : 36827360
total throughput              : 40918992

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=20.0253
PAPI_L3_TCM=6.46468
PAPI_TOT_CYC=6519.86
PAPI_TOT_INS=2414.09
garbage=1841192849966707
total_execution_walltime=16.394s
REALTIME_MAIN_END_PERF_FORMAT=6992335.240906
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 446123231
[slow htm]
[fallback]
total fallback commit         : 646349
seconds global lock is held   : 5.38637
[time_cmd_output] time_elapsed_sec=16.55, faults_major=0, faults_minor=242107, mem_maxresident_kb=664972, user_cputime=1540.30, sys_cputime=5.50, percent_cpu=9338%
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=1