__step=000228
__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 96 -nprefill 96 -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=data000228.txt
__path_data=data/data000228.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997235.327559701
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=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


Hybrid noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.016s
prefilling_size=270620 prefilling_elapsed_millis=1.001s
prefilling_size=293136 prefilling_elapsed_millis=1.101s
prefilling_size=315407 prefilling_elapsed_millis=1.201s
prefilling_size=336820 prefilling_elapsed_millis=1.301s
prefilling_size=357535 prefilling_elapsed_millis=1.402s
prefilling_size=377671 prefilling_elapsed_millis=1.502s
prefilling_size=397197 prefilling_elapsed_millis=1.602s
prefilling_size=415930 prefilling_elapsed_millis=1.702s
prefilling_size=433877 prefilling_elapsed_millis=1.802s
prefilling_size=451563 prefilling_elapsed_millis=1.903s
prefilling_size=468605 prefilling_elapsed_millis=2.003s
prefilling_size=485119 prefilling_elapsed_millis=2.103s
prefilling_size=501332 prefilling_elapsed_millis=2.203s
prefilling_size=516832 prefilling_elapsed_millis=2.303s
prefilling_size=531964 prefilling_elapsed_millis=2.404s
prefilling_size=546589 prefilling_elapsed_millis=2.504s
prefilling_size=560645 prefilling_elapsed_millis=2.604s
prefilling_size=574253 prefilling_elapsed_millis=2.704s
prefilling_size=587448 prefilling_elapsed_millis=2.804s
prefilling_size=600658 prefilling_elapsed_millis=2.905s
prefilling_size=612856 prefilling_elapsed_millis=3.005s
prefilling_size=624971 prefilling_elapsed_millis=3.105s
prefilling_size=636295 prefilling_elapsed_millis=3.205s
prefilling_size=647476 prefilling_elapsed_millis=3.305s
prefilling_size=658347 prefilling_elapsed_millis=3.406s
prefilling_size=668780 prefilling_elapsed_millis=3.506s
prefilling_size=678868 prefilling_elapsed_millis=3.606s
prefilling_size=688941 prefilling_elapsed_millis=3.706s
prefilling_size=698669 prefilling_elapsed_millis=3.806s
prefilling_size=707980 prefilling_elapsed_millis=3.907s
prefilling_size=716894 prefilling_elapsed_millis=4.007s
prefilling_size=725761 prefilling_elapsed_millis=4.107s
prefilling_size=734084 prefilling_elapsed_millis=4.207s
prefilling_size=742482 prefilling_elapsed_millis=4.307s
prefilling_size=750464 prefilling_elapsed_millis=4.407s
prefilling_size=758438 prefilling_elapsed_millis=4.508s
prefilling_size=765844 prefilling_elapsed_millis=4.608s
prefilling_size=773295 prefilling_elapsed_millis=4.708s
prefilling_size=780473 prefilling_elapsed_millis=4.808s
prefilling_size=787285 prefilling_elapsed_millis=4.908s
prefilling_size=793676 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 206302 keys with 96 threads...
tid=0 thread_prefill_amount=8332 percent_done_estimate=387.7 elapsed_s=6 est_remaining_s=-9 / -4
timing_elapsed 0.581251s
prefilling_size=1000000 prefilling_elapsed_millis=0.582s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000494649456, performing 3548097 updates; total_prefilling_elapsed_ms=582 ms)
pref_size=1000000
pref_millis=582
prefill_elapsed_ms=582
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=55

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6997241.228163246
REALTIME_END_PERF_FORMAT=6997251.228271117

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

tree_stats_computeWalltime=0.01s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 503 963 1798 3199 5393 8625 13172 19021 25959 33443 40813 46853 51491 54176 54354 52590 48884 43516 37033 30305 23845 18243 13550 9643 6668 4442 2711 1517 849 471 267 142 66 28 6 4 1 2 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 3 24 70 208 546 1197 2290 3947 6433 9940 13964 18540 22384 25811 28523 29498 29731 28456 26259 22855 19261 15469 11966 9131 6597 4615 3130 2039 1104 640 359 203 107 58 33 4 4 0 1 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 506 987 1868 3407 5939 9822 15462 22968 32392 43383 54777 65393 73875 79987 82877 82088 78615 71972 63292 53160 43106 33712 25516 18774 13265 9057 5841 3556 1953 1111 626 345 173 86 39 8 5 2 2 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 506 987 1868 3407 5939 9822 15462 22968 32392 43383 54777 65393 73875 79987 82877 82088 78615 71972 63292 53160 43106 33712 25516 18774 13265 9057 5841 3556 1953 1111 626 345 173 86 39 8 5 2 2 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.97656 1.95652 1.91692 1.86135 1.80423 1.74575 1.69609 1.63355 1.58216 1.53791 1.49176 1.44873 1.41322 1.38573 1.35882 1.33464 1.31704 1.29368 1.27477 1.2548 1.2408 1.2289 1.21574 1.20473 1.19293 1.1801 1.15447 1.14467 1.12261 1.13415 1.13951 1.1246 1.08986 1.11561 1.12791 1.05128 1.125 1.2 1 1 1

tree_stats_height=49
tree_stats_numInternals=655058
tree_stats_numLeaves=345401
tree_stats_numNodes=1000459
tree_stats_numKeys=1000459

tree_stats_avgDegreeInternal=1.52728
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34524
tree_stats_avgKeyDepth=23.6815

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:25 4:2881 8:62478 16:43951 32:9
    (2^01, 2^02]: 25
    (2^02, 2^03]: 2881
    (2^03, 2^04]: 62478
    (2^04, 2^05]: 43951
    (2^05, 2^06]: 9
sum_limbo_reclamation_event_size_total=1607504
count_limbo_reclamation_event_size_by_thread=1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139 1139
count_limbo_reclamation_event_size_total=109344
sum_limbo_reclamation_event_count_total=109597
first_thread_announced_epoch_by_thread=2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392 2392
sum_num_inserts_by_thread=29600 28064 27236 27797 27294 26663 29792 27667 27807 27469 27741 27782 29788 27704 27102 27857 27140 27445 29646 27623 27551 27388 26745 27772 29695 26768 27400 27698 26715 27699 30048 27121 27524 27501 28208 28114 29461 26792 27297 27471 27784 27083 29970 27256 27979 27471 26773 26817 42203 38731 38219 38975 37380 38272 41847 39467 37953 39443 38377 38667 43052 39606 39175 38783 37519 38181 42006 38779 39332 39040 38613 38629 41544 39121 38798 38754 38592 38558 42549 39664 39060 39475 37957 38661 42868 39973 39163 39116 38018 37121 42939 38653 39153 39818 38217 38645
sum_num_inserts_total=3223984
sum_num_deletes_by_thread=29592 27798 27146 27196 27154 26326 29816 27591 27683 27293 28041 27584 30005 27503 27266 27721 27024 27198 30379 27603 27442 27599 26871 27332 29704 26707 27891 27670 26789 27609 30311 27047 27497 27498 27942 27831 29429 26923 27099 27613 27863 26710 30285 27198 27708 27490 26915 26763 42359 38685 38053 38748 37381 38088 42358 39361 38374 39626 38129 38770 43177 39924 39193 38441 38063 38416 42841 39283 39286 38757 38612 38670 41538 39259 38768 39042 38618 38877 42517 39809 39113 38956 37706 38806 43059 39858 38779 38746 38004 37173 42724 38951 38683 39558 37995 38527
sum_num_deletes_total=3223316
sum_num_searches_by_thread=532162 504164 488552 493941 493171 473996 537722 498082 497722 490488 506340 497732 540030 496333 489344 499823 489397 490675 543597 497669 493882 493503 480451 497302 531865 482234 498961 496663 481531 495151 548454 483637 498758 497544 500172 507386 532004 482522 491394 497611 500326 482904 541474 489451 499215 492193 478589 482985 763648 702051 683233 695070 671231 686486 762376 706502 687976 713267 685678 690911 773728 712577 707593 692683 680616 687684 765574 703126 704620 699366 691568 692015 752342 703016 703307 702644 691127 696398 767284 718854 708030 701010 680547 697372 774484 719353 701486 696619 682463 670551 765688 698206 703660 716648 681033 693449
sum_num_searches_total=58004252
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=591354 560026 542934 548934 547619 526985 597330 553340 553212 545250 562122 553098 599823 551540 543712 555401 543561 545318 603622 552895 548875 548490 534067 552406 591264 535709 554252 552031 535035 550459 608813 537805 553779 552543 556322 563331 590894 536237 545790 552695 555973 536697 601729 543905 554902 547154 532277 536565 848210 779467 759505 772793 745992 762846 846581 785330 764303 792336 762184 768348 859957 792107 785961 769907 756198 764281 850421 781188 783238 777163 768793 769314 835424 781396 780873 780440 768337 773833 852350 798327 786203 779441 756210 774839 860411 799184 779428 774481 758485 744845 851351 775810 781496 796024 757245 770621
average_num_operations_total=671370
stdev_num_operations_total=12204
sum_num_operations_total=64451552
min_num_operations_total=526985
max_num_operations_total=860411
first_time_thread_terminate_by_thread=10000233 10000213 10000230 10000232 10000185 10000235 10000110 10000218 10000228 10000208 10000224 10000229 10000147 10000216 10000230 10000222 10000231 10000226 10000214 10000127 10000219 10000223 10000234 10000211 10000233 10000217 10000229 10000232 10000210 10000235 10000225 10000183 10000227 10000155 10000106 10000158 10000223 10000221 10000227 10000145 10000141 10000233 10000216 10000129 10000139 10000220 10000225 10000108 10000169 10000195 10000140 10000204 10000106 10000133 10000114 10000163 10000209 10000193 10000107 10000119 10000116 10000148 10000196 10000182 10000171 10000108 10000142 10000189 10000192 10000113 10000180 10000198 10000178 10000165 10000107 10000205 10000137 10000135 10000167 10000162 10000203 10000190 10000173 10000106 10000107 10000121 10000106 10000187 10000151 10000109 10000201 10000176 10000186 10000153 10000175 10000200
min_time_thread_terminate_total=10000106
max_time_thread_terminate_total=10000235
first_time_thread_start_by_thread=8 4 4 4 4 4 4 4 5 4 4 4 4 4 4 4 4 4 4 4 4 4 4 5 3 4 4 4 4 4 4 4 5 4 4 4 4 4 4 4 4 4 4 4 4 4 4 5 5 5 5 4 5 4 5 5 5 5 5 5 5 5 5 4 5 5 4 4 5 5 4 5 5 5 5 4 5 4 5 5 5 5 5 5 5 5 5 4 5 5 4 4 5 5 4 5
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=1000328804103
threads_final_size=1000459
final_keysum=1000328804103
final_size=1000459
validate_result=success
Validation OK.
Structural validation OK.

total_find=58004252
total_rq=0
total_inserts=3223984
total_deletes=3223316
total_updates=6447300
total_queries=58004252
total_ops=64451552
find_throughput=5800425
rq_throughput=0
update_throughput=644730
query_throughput=5800425
total_throughput=6445155


total find                    : 58004252
total rq                      : 0
total inserts                 : 3223984
total deletes                 : 3223316
total updates                 : 6447300
total queries                 : 58004252
total ops                     : 64451552
find throughput               : 5800425
rq throughput                 : 0
update throughput             : 644730
query throughput              : 5800425
total throughput              : 6445155

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=19.1315
PAPI_L3_TCM=2.56495
PAPI_TOT_CYC=41316.7
PAPI_TOT_INS=5165.11
garbage=28990833502820
total_execution_walltime=16.018s
REALTIME_MAIN_END_PERF_FORMAT=6997251.356048079
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 64122626
[slow htm]
[fallback]
total fallback commit         : 3877024
seconds global lock is held   : 0.315889
[time_cmd_output] time_elapsed_sec=16.16, faults_major=0, faults_minor=91339, mem_maxresident_kb=363424, user_cputime=1510.59, sys_cputime=5.26, percent_cpu=9377%
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=2