__step=000230
__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 144 -nprefill 144 -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=data000230.txt
__path_data=data/data000230.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997268.380224813
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=144
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=144
WORK_THREADS=144
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,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63,64,65,66,67,68,69,70,71,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167


Hybrid noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.025s
prefilling_size=206190 prefilling_elapsed_millis=1.001s
prefilling_size=224365 prefilling_elapsed_millis=1.101s
prefilling_size=242101 prefilling_elapsed_millis=1.201s
prefilling_size=259547 prefilling_elapsed_millis=1.301s
prefilling_size=276521 prefilling_elapsed_millis=1.402s
prefilling_size=292983 prefilling_elapsed_millis=1.502s
prefilling_size=308999 prefilling_elapsed_millis=1.602s
prefilling_size=324909 prefilling_elapsed_millis=1.702s
prefilling_size=340318 prefilling_elapsed_millis=1.802s
prefilling_size=355301 prefilling_elapsed_millis=1.903s
prefilling_size=370118 prefilling_elapsed_millis=2.003s
prefilling_size=384574 prefilling_elapsed_millis=2.103s
prefilling_size=398675 prefilling_elapsed_millis=2.203s
prefilling_size=412551 prefilling_elapsed_millis=2.303s
prefilling_size=425776 prefilling_elapsed_millis=2.404s
prefilling_size=439030 prefilling_elapsed_millis=2.504s
prefilling_size=451731 prefilling_elapsed_millis=2.604s
prefilling_size=464190 prefilling_elapsed_millis=2.704s
prefilling_size=476592 prefilling_elapsed_millis=2.804s
prefilling_size=488414 prefilling_elapsed_millis=2.904s
prefilling_size=500026 prefilling_elapsed_millis=3.004s
prefilling_size=511484 prefilling_elapsed_millis=3.105s
prefilling_size=523056 prefilling_elapsed_millis=3.205s
prefilling_size=533977 prefilling_elapsed_millis=3.305s
prefilling_size=544661 prefilling_elapsed_millis=3.405s
prefilling_size=555225 prefilling_elapsed_millis=3.505s
prefilling_size=565390 prefilling_elapsed_millis=3.606s
prefilling_size=575414 prefilling_elapsed_millis=3.706s
prefilling_size=585155 prefilling_elapsed_millis=3.806s
prefilling_size=594469 prefilling_elapsed_millis=3.906s
prefilling_size=603556 prefilling_elapsed_millis=4.006s
prefilling_size=612552 prefilling_elapsed_millis=4.107s
prefilling_size=621397 prefilling_elapsed_millis=4.207s
prefilling_size=630105 prefilling_elapsed_millis=4.307s
prefilling_size=638524 prefilling_elapsed_millis=4.407s
prefilling_size=646890 prefilling_elapsed_millis=4.507s
prefilling_size=655172 prefilling_elapsed_millis=4.608s
prefilling_size=662808 prefilling_elapsed_millis=4.708s
prefilling_size=670325 prefilling_elapsed_millis=4.808s
prefilling_size=677892 prefilling_elapsed_millis=4.908s
prefilling_size=685238 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 314717 keys with 144 threads...
tid=0 thread_prefill_amount=4164 percent_done_estimate=190.5 elapsed_s=6 est_remaining_s=-6 / -0
tid=0 thread_prefill_amount=5552 percent_done_estimate=254.0 elapsed_s=6 est_remaining_s=-9 / -2
timing_elapsed 1.24959s
prefilling_size=1000000 prefilling_elapsed_millis=1.252s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000178402017, performing 2862797 updates; total_prefilling_elapsed_ms=1252 ms)
pref_size=1000000
pref_millis=1252
prefill_elapsed_ms=1253
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=18

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6997275.10079793
REALTIME_END_PERF_FORMAT=6997285.10232213

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

tree_stats_computeWalltime=0.015s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 252 489 927 1696 2934 4826 7637 11445 16558 22509 29161 36150 42201 47314 50799 52393 51785 48972 44668 39683 33951 28459 23010 17680 13209 9567 6701 4582 2923 1664 930 514 261 148 52 19 9 3 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 5 30 81 250 593 1069 2088 3356 5696 8468 11799 16017 19536 23162 25854 27733 28534 27909 25924 23766 20477 17755 14851 11416 8756 6383 4556 3205 2171 1233 682 393 186 133 44 16 7 3
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 127 253 494 957 1777 3184 5419 8706 13533 19914 28205 37629 47949 58218 66850 73961 78247 79518 77506 72577 65607 57717 48936 40765 32531 24625 18323 13084 9138 6128 3835 2163 1196 654 334 185 63 25 10 3
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 127 253 494 957 1777 3184 5419 8706 13533 19914 28205 37629 47949 58218 66850 73961 78247 79518 77506 72577 65607 57717 48936 40765 32531 24625 18323 13084 9138 6128 3835 2163 1196 654 334 185 63 25 10 3
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.98438 1.99213 1.95652 1.94737 1.88819 1.83737 1.78046 1.716 1.67723 1.6258 1.58486 1.53608 1.4993 1.46024 1.42339 1.39861 1.37111 1.34666 1.32346 1.30456 1.28851 1.27488 1.25963 1.25147 1.23356 1.21349 1.20768 1.19194 1.18626 1.16918 1.14883 1.13012 1.12298 1.11706 1.11162 1.11078 1.05946 1.09524 1.04 1 1

tree_stats_height=47
tree_stats_numInternals=656335
tree_stats_numLeaves=344138
tree_stats_numNodes=1000473
tree_stats_numKeys=1000473

tree_stats_avgDegreeInternal=1.52433
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34398
tree_stats_avgKeyDepth=24.3125

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:45 8:7738 16:40598 32:3885
    (2^02, 2^03]: 45
    (2^03, 2^04]: 7738
    (2^04, 2^05]: 40598
    (2^05, 2^06]: 3885
sum_limbo_reclamation_event_size_total=1177606
count_limbo_reclamation_event_size_by_thread=363 363 363 362 363 363 362 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 362 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 362 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 362 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 362 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363 363
count_limbo_reclamation_event_size_total=52266
sum_limbo_reclamation_event_count_total=52678
first_thread_announced_epoch_by_thread=766 766 766 764 766 766 764 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 764 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 764 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 764 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 764 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766 766
sum_num_inserts_by_thread=11402 11391 11637 11396 11412 12311 11529 11423 11961 11311 11790 12558 11502 11462 11624 11551 11791 13039 11514 11512 11509 11544 11465 13342 11409 11386 11556 11286 11774 12849 11696 11607 11667 11501 11972 12405 11719 11535 11820 11843 11939 13043 11505 11301 11250 11857 11464 12684 17298 17192 17715 17776 18185 17992 17973 17438 18539 17560 18042 19269 17397 17687 17637 17882 18379 18654 17011 17097 17894 18081 17337 19180 16881 16987 16790 17117 18239 18265 17878 16466 17224 17634 18141 19136 17458 17711 17895 18025 18408 18960 17654 17402 18051 18165 18018 18932 19226 18613 19677 19554 19728 19437 19707 20120 19900 19426 19367 21251 19623 19613 19428 18903 19557 21566 19554 19394 19311 19855 20292 21401 19016 18495 19431 18968 19320 19933 19207 19956 19762 19215 19598 21140 19640 19651 19170 19692 20001 20694 19692 19340 19580 19597 20006 21251
sum_num_inserts_total=2368554
sum_num_deletes_by_thread=11337 11303 11600 11661 11879 12454 11413 11440 12039 11364 11804 12514 11472 11496 11769 11981 11503 13123 11608 11573 11641 11443 11289 13215 11424 11401 11543 11513 11677 13070 11480 11830 11652 11683 12068 12044 11725 11546 11695 11668 11863 12990 11579 11266 11319 11729 11743 12765 17562 17217 17511 17819 18171 17859 17874 17592 18349 17712 18164 19078 17541 17361 17616 17680 18174 18812 17276 16984 17884 18266 17509 19486 16867 16862 17061 17322 18070 18299 17926 16366 17499 17747 17989 18997 17236 17854 18201 17659 18215 18930 17639 17267 18262 18367 18078 18787 19570 18857 19611 19382 19536 19485 19613 20030 20330 19651 19481 21217 19674 19466 19342 19072 19585 21597 19724 19337 18984 19513 20750 21237 19126 18548 19533 19027 19158 19646 19400 19884 20020 19494 19544 21089 19885 19389 19149 19527 19989 20519 19433 19757 19277 19381 19791 21305
sum_num_deletes_total=2370106
sum_num_searches_by_thread=204501 202460 206007 208962 210264 223176 208456 205070 216821 203564 212288 224444 206969 207308 213064 208337 210387 236780 205220 210717 207812 205819 203905 236770 202042 204015 207785 204558 213596 234238 208679 208847 209692 209853 215626 220747 210471 209043 212315 211655 218400 234307 208481 203188 201861 213111 205646 229154 313456 306775 316500 322499 329507 320417 324252 318013 332165 316326 327678 344253 313537 314988 318446 318356 326164 332986 308711 308840 323637 329371 312056 344635 303608 304579 307893 309592 323697 328942 321722 294740 311032 322624 323859 341170 313279 319296 326269 320919 330490 337989 318735 313971 326065 327365 325899 339438 342870 338572 353046 349976 355439 350301 354684 360479 361996 354157 348759 383458 354089 352357 348316 342904 350662 388339 352145 351037 347022 355236 369576 385249 340561 332765 352045 341638 347519 356886 346667 359427 356963 350507 351088 380204 356902 350246 342720 351511 364182 370522 353711 350418 347231 353367 358924 381747
sum_num_searches_total=42641572
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=227240 225154 229244 232019 233555 247941 231398 227933 240821 226239 235882 249516 229943 230266 236457 231869 233681 262942 228342 233802 230962 228806 226659 263327 224875 226802 230884 227357 237047 260157 231855 232284 233011 233037 239666 245196 233915 232124 235830 235166 242202 260340 231565 225755 224430 236697 228853 254603 348316 341184 351726 358094 365863 356268 360099 353043 369053 351598 363884 382600 348475 350036 353699 353918 362717 370452 342998 342921 359415 365718 346902 383301 337356 338428 341744 344031 360006 365506 357526 327572 345755 358005 359989 379303 347973 354861 362365 356603 367113 375879 354028 348640 362378 363897 361995 377157 381666 376042 392334 388912 394703 389223 394004 400629 402226 393234 387607 425926 393386 391436 387086 380879 389804 431502 391423 389768 385317 394604 410618 427887 378703 369808 391009 379633 385997 396465 385274 399267 396745 389216 390230 422433 396427 389286 381039 390730 404172 411735 392836 389515 386088 392345 398721 424303
average_num_operations_total=329029
stdev_num_operations_total=5780
sum_num_operations_total=47380232
min_num_operations_total=224430
max_num_operations_total=431502
first_time_thread_terminate_by_thread=10000443 10000434 10000442 10000447 10000452 10000441 10000300 10000415 10000371 10000399 10000242 10000386 10000369 10000262 10000382 10000261 10000384 10000397 10000419 10000452 10000448 10000187 10000412 10000446 10000152 10000434 10000379 10000425 10000453 10000432 10000451 10000445 10000173 10000152 10000168 10000444 10000152 10000174 10000181 10000312 10000416 10000350 10000450 10000452 10000319 10000352 10000162 10000347 10000418 10000196 10000154 10000151 10000286 10000212 10000380 10000153 10000430 10000373 10000336 10000332 10000400 10000392 10000284 10000335 10000427 10000378 10000231 10000209 10000277 10000246 10000201 10000289 10000206 10000358 10000279 10000391 10000356 10000374 10000164 10000174 10000439 10000217 10000221 10000291 10000401 10000228 10000177 10000199 10000428 10000151 10000215 10000282 10000440 10000354 10000424 10000154 10000156 10000329 10000236 10000195 10000341 10000257 10000321 10000309 10000308 10000184 10000255 10000366 10000405 10000292 10000407 10000153 10000422 10000267 10000395 10000448 10000251 10000321 10000360 10000152 10000265 10000300 10000239 10000339 10000317 10000316 10000327 10000410 10000436 10000364 10000249 10000344 10000404 10000272 10000388 10000303 10000437 10000394 10000152 10000449 10000156 10000362 10000268 10000338
min_time_thread_terminate_total=10000151
max_time_thread_terminate_total=10000453
first_time_thread_start_by_thread=11 4 4 4 4 4 5 4 4 4 4 4 4 4 5 4 4 4 4 4 4 4 4 4 3 4 4 4 5 4 5 4 4 4 4 4 4 4 5 4 4 4 4 4 4 4 4 4 4 4 5 5 4 4 4 4 4 4 5 4 4 5 4 4 4 4 4 5 5 5 4 4 4 4 5 5 4 4 4 4 4 4 5 4 4 5 4 4 4 4 4 5 5 5 4 4 5 4 4 4 4 4 4 4 4 4 5 4 4 4 5 4 4 5 4 5 4 4 4 4 5 4 4 4 4 4 4 4 4 4 5 4 4 4 5 4 4 5 4 5 4 4 4 4
min_time_thread_start_total=3
max_time_thread_start_total=11
sum_duration_all_ops_total=

threads_final_keysum=1000381917018
threads_final_size=1000473
final_keysum=1000381917018
final_size=1000473
validate_result=success
Validation OK.
Structural validation OK.

total_find=42641572
total_rq=0
total_inserts=2368554
total_deletes=2370106
total_updates=4738660
total_queries=42641572
total_ops=47380232
find_throughput=4264157
rq_throughput=0
update_throughput=473866
query_throughput=4264157
total_throughput=4738023


total find                    : 42641572
total rq                      : 0
total inserts                 : 2368554
total deletes                 : 2370106
total updates                 : 4738660
total queries                 : 42641572
total ops                     : 47380232
find throughput               : 4264157
rq throughput                 : 0
update throughput             : 473866
query throughput              : 4264157
total throughput              : 4738023

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=23.8879
PAPI_L3_TCM=3.91074
PAPI_TOT_CYC=84391.8
PAPI_TOT_INS=7658.89
garbage=20958585831423
total_execution_walltime=16.742s
REALTIME_MAIN_END_PERF_FORMAT=6997285.138794338
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 46842014
[slow htm]
[fallback]
total fallback commit         : 3401016
seconds global lock is held   : 0.300454
[time_cmd_output] time_elapsed_sec=16.88, faults_major=0, faults_minor=98547, mem_maxresident_kb=389832, user_cputime=2358.21, sys_cputime=5.55, percent_cpu=13995%
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