__step=000053
__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_sigouin_int_avl_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 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000053.txt
__path_data=data/data000053.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992878.969579552
binary=./brown_sigouin_int_avl_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_sigouin_int_avl_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=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=48
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.065s
prefilling_size=256088 prefilling_elapsed_millis=1s
prefilling_size=281426 prefilling_elapsed_millis=1.1s
prefilling_size=306791 prefilling_elapsed_millis=1.201s
prefilling_size=331959 prefilling_elapsed_millis=1.301s
prefilling_size=357158 prefilling_elapsed_millis=1.401s
prefilling_size=382175 prefilling_elapsed_millis=1.501s
prefilling_size=407321 prefilling_elapsed_millis=1.601s
prefilling_size=432424 prefilling_elapsed_millis=1.702s
prefilling_size=457273 prefilling_elapsed_millis=1.802s
prefilling_size=482203 prefilling_elapsed_millis=1.902s
prefilling_size=506961 prefilling_elapsed_millis=2.002s
prefilling_size=531583 prefilling_elapsed_millis=2.102s
prefilling_size=556038 prefilling_elapsed_millis=2.203s
prefilling_size=580590 prefilling_elapsed_millis=2.303s
prefilling_size=604929 prefilling_elapsed_millis=2.403s
prefilling_size=629332 prefilling_elapsed_millis=2.503s
prefilling_size=653626 prefilling_elapsed_millis=2.604s
prefilling_size=677873 prefilling_elapsed_millis=2.704s
prefilling_size=702081 prefilling_elapsed_millis=2.804s
prefilling_size=726216 prefilling_elapsed_millis=2.904s
prefilling_size=750316 prefilling_elapsed_millis=3.004s
prefilling_size=774423 prefilling_elapsed_millis=3.105s
prefilling_size=798363 prefilling_elapsed_millis=3.205s
prefilling_size=822219 prefilling_elapsed_millis=3.305s
prefilling_size=846062 prefilling_elapsed_millis=3.405s
prefilling_size=869779 prefilling_elapsed_millis=3.506s
prefilling_size=893242 prefilling_elapsed_millis=3.606s
prefilling_size=916943 prefilling_elapsed_millis=3.706s
prefilling_size=940383 prefilling_elapsed_millis=3.806s
prefilling_size=963874 prefilling_elapsed_millis=3.907s
prefilling_size=987227 prefilling_elapsed_millis=4.007s
prefilling_size=1010608 prefilling_elapsed_millis=4.107s
prefilling_size=1033684 prefilling_elapsed_millis=4.207s
prefilling_size=1056887 prefilling_elapsed_millis=4.307s
prefilling_size=1080059 prefilling_elapsed_millis=4.408s
prefilling_size=1103378 prefilling_elapsed_millis=4.508s
prefilling_size=1126230 prefilling_elapsed_millis=4.608s
prefilling_size=1149111 prefilling_elapsed_millis=4.708s
prefilling_size=1172044 prefilling_elapsed_millis=4.809s
prefilling_size=1194694 prefilling_elapsed_millis=4.909s
prefilling_size=1217272 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 8782577 keys with 144 threads...
tid=0 thread_prefill_amount=6940 percent_done_estimate=11.4 elapsed_s=6 est_remaining_s=111 / 117
tid=0 thread_prefill_amount=8328 percent_done_estimate=13.7 elapsed_s=8 est_remaining_s=108 / 116
tid=0 thread_prefill_amount=9716 percent_done_estimate=15.9 elapsed_s=9 est_remaining_s=105 / 114
tid=0 thread_prefill_amount=11104 percent_done_estimate=18.2 elapsed_s=10 est_remaining_s=102 / 112
tid=0 thread_prefill_amount=12492 percent_done_estimate=20.5 elapsed_s=11 est_remaining_s=99 / 110
tid=0 thread_prefill_amount=13880 percent_done_estimate=22.8 elapsed_s=13 est_remaining_s=96 / 109
tid=0 thread_prefill_amount=15268 percent_done_estimate=25.0 elapsed_s=14 est_remaining_s=93 / 107
tid=0 thread_prefill_amount=16656 percent_done_estimate=27.3 elapsed_s=15 est_remaining_s=91 / 106
tid=0 thread_prefill_amount=18044 percent_done_estimate=29.6 elapsed_s=16 est_remaining_s=88 / 104
tid=0 thread_prefill_amount=19432 percent_done_estimate=31.9 elapsed_s=18 est_remaining_s=85 / 102
tid=0 thread_prefill_amount=20820 percent_done_estimate=34.1 elapsed_s=19 est_remaining_s=82 / 101
tid=0 thread_prefill_amount=22208 percent_done_estimate=36.4 elapsed_s=20 est_remaining_s=79 / 99
tid=0 thread_prefill_amount=23596 percent_done_estimate=38.7 elapsed_s=21 est_remaining_s=76 / 98
tid=0 thread_prefill_amount=24984 percent_done_estimate=41.0 elapsed_s=23 est_remaining_s=74 / 96
tid=0 thread_prefill_amount=26372 percent_done_estimate=43.2 elapsed_s=24 est_remaining_s=71 / 95
tid=0 thread_prefill_amount=27760 percent_done_estimate=45.5 elapsed_s=25 est_remaining_s=68 / 93
tid=0 thread_prefill_amount=29148 percent_done_estimate=47.8 elapsed_s=27 est_remaining_s=65 / 92
tid=0 thread_prefill_amount=30536 percent_done_estimate=50.1 elapsed_s=28 est_remaining_s=62 / 90
tid=0 thread_prefill_amount=31924 percent_done_estimate=52.3 elapsed_s=29 est_remaining_s=60 / 89
tid=0 thread_prefill_amount=33312 percent_done_estimate=54.6 elapsed_s=30 est_remaining_s=56 / 87
tid=0 thread_prefill_amount=34700 percent_done_estimate=56.9 elapsed_s=31 est_remaining_s=52 / 83
tid=0 thread_prefill_amount=36088 percent_done_estimate=59.2 elapsed_s=31 est_remaining_s=48 / 79
tid=0 thread_prefill_amount=37476 percent_done_estimate=61.4 elapsed_s=32 est_remaining_s=45 / 76
tid=0 thread_prefill_amount=38864 percent_done_estimate=63.7 elapsed_s=32 est_remaining_s=41 / 73
tid=0 thread_prefill_amount=40252 percent_done_estimate=66.0 elapsed_s=32 est_remaining_s=38 / 70
tid=0 thread_prefill_amount=41640 percent_done_estimate=68.3 elapsed_s=33 est_remaining_s=34 / 67
tid=0 thread_prefill_amount=43028 percent_done_estimate=70.5 elapsed_s=33 est_remaining_s=31 / 64
tid=0 thread_prefill_amount=44416 percent_done_estimate=72.8 elapsed_s=33 est_remaining_s=28 / 60
tid=0 thread_prefill_amount=45804 percent_done_estimate=75.1 elapsed_s=33 est_remaining_s=25 / 57
tid=0 thread_prefill_amount=47192 percent_done_estimate=77.4 elapsed_s=33 est_remaining_s=22 / 55
tid=0 thread_prefill_amount=48580 percent_done_estimate=79.7 elapsed_s=33 est_remaining_s=19 / 52
tid=0 thread_prefill_amount=49968 percent_done_estimate=81.9 elapsed_s=33 est_remaining_s=16 / 50
tid=0 thread_prefill_amount=51356 percent_done_estimate=84.2 elapsed_s=33 est_remaining_s=14 / 47
tid=0 thread_prefill_amount=52744 percent_done_estimate=86.5 elapsed_s=33 est_remaining_s=12 / 45
tid=0 thread_prefill_amount=54132 percent_done_estimate=88.8 elapsed_s=33 est_remaining_s=10 / 43
tid=0 thread_prefill_amount=55520 percent_done_estimate=91.0 elapsed_s=33 est_remaining_s=7 / 41
tid=0 thread_prefill_amount=56908 percent_done_estimate=93.3 elapsed_s=34 est_remaining_s=5 / 39
tid=0 thread_prefill_amount=58296 percent_done_estimate=95.6 elapsed_s=34 est_remaining_s=3 / 37
tid=0 thread_prefill_amount=59684 percent_done_estimate=97.9 elapsed_s=34 est_remaining_s=2 / 35
tid=0 thread_prefill_amount=61072 percent_done_estimate=100.1 elapsed_s=34 est_remaining_s=-0 / 34
tid=0 thread_prefill_amount=62460 percent_done_estimate=102.4 elapsed_s=34 est_remaining_s=-2 / 32
tid=0 thread_prefill_amount=63848 percent_done_estimate=104.7 elapsed_s=34 est_remaining_s=-3 / 31
tid=0 thread_prefill_amount=65236 percent_done_estimate=107.0 elapsed_s=34 est_remaining_s=-5 / 29
timing_elapsed 28.9349s
prefilling_size=10000000 prefilling_elapsed_millis=29.004s
finished prefilling to size 10000000 for expected size 10000000 keysum=100008584635574, performing 15206712 updates; total_prefilling_elapsed_ms=29004 ms)
pref_size=10000000
pref_millis=29004
prefill_elapsed_ms=29004
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6992913.356171965
REALTIME_END_PERF_FORMAT=6992923.356319717

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

tree_stats_computeWalltime=0.022s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131065 261651 514454 939093 1388704 1382358 771305 198964 17520 212 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 7 430 7742 75998 398581 1084061 1474915 944981 254155 22074 257
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262081 522196 1015091 1787285 2466419 2246220 1143945 271675 22286 257
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262081 522196 1015091 1787285 2466419 2246220 1143945 271675 22286 257
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99957 1.99414 1.95871 1.83558 1.60299 1.35025 1.1659 1.06356 1.01754 1.00202 1

tree_stats_height=28
tree_stats_numInternals=5736397
tree_stats_numLeaves=4263201
tree_stats_numNodes=9999598
tree_stats_numKeys=9999598

tree_stats_avgDegreeInternal=1.74318
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42634
tree_stats_avgKeyDepth=21.7434


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0
    [2^00, 2^01]: 0
sum_limbo_reclamation_event_size_total=
count_limbo_reclamation_event_size_by_thread=
count_limbo_reclamation_event_size_total=
sum_limbo_reclamation_event_count_total=59748
first_thread_announced_epoch_by_thread=1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110 1110
sum_num_inserts_by_thread=11869 11178 11002 11089 10983 10781 12126 11234 11346 11012 11281 11027 12048 11162 11229 10992 10836 10871 12100 10905 10994 11441 11144 10894 12078 11143 11247 11201 11118 10879 12168 11320 11328 11099 11383 11146 12180 10944 11137 11074 11173 10910 11995 11016 11096 11114 11059 10943 24453 22609 22666 22249 22202 22682 24804 23020 22577 22538 22604 22529 24390 22762 22776 22273 22674 22302 24619 22458 23000 22942 23209 22313 24570 22567 22747 22703 22664 22309 24641 23370 22445 23001 22862 22247 25123 22893 22909 22702 22667 22260 24757 22886 22955 23057 22767 23129 20579 19496 19326 19425 19460 19034 21193 19854 19503 19472 19489 19015 21070 19394 19330 19351 19235 19064 20938 19704 19007 19205 19548 19094 20655 19540 18888 19164 19218 19079 20646 19644 19372 19437 19008 19024 20787 19210 19262 19255 19130 19187 20986 19443 19317 19037 19028 19038
sum_num_inserts_total=2583318
sum_num_deletes_by_thread=11602 11131 10993 11259 11288 10827 12213 11389 11241 11356 11121 11059 12050 10972 11070 11158 10817 11050 11953 11311 11062 11353 11181 11204 12036 11112 10783 11380 11150 10761 12226 11363 11252 11200 11120 11199 11923 11111 11377 11100 10905 10880 12035 11166 11102 11100 11065 11060 24491 23123 22547 22563 22179 22526 24837 23273 22381 22590 22776 22704 24724 22499 22634 22589 22748 22203 24793 22702 23053 22861 22911 22712 24618 22515 22409 22531 22216 22250 24581 23198 22317 22951 23209 22492 24748 22775 22917 22972 22954 22192 24826 22801 23060 22921 22945 22912 20786 19454 19497 19648 19314 18694 20724 19508 19523 19565 19414 19069 20722 19386 19311 19215 19240 18710 21174 19738 19257 19316 19515 19424 20766 19513 19027 19215 19200 19088 20613 19898 19253 19646 18959 18773 20644 19439 19288 19580 19107 19372 21087 19603 19219 19178 18941 18886
sum_num_deletes_total=2585294
sum_num_searches_by_thread=209610 198487 198541 204173 199160 195199 217871 202384 202669 201984 202047 198223 218885 198671 199533 198914 194653 197840 216729 201001 200151 204146 200729 198072 213596 200055 196340 200534 199691 196600 215945 204282 202004 200261 199883 201497 218323 199518 202552 200438 198064 194359 219776 197784 198828 199305 198175 198202 441972 408899 402662 402620 396213 407334 449021 414425 404439 403876 407712 408469 442544 407391 407155 402937 409860 401568 442061 409803 416917 408846 415051 405704 445159 407130 403246 405882 403997 401270 442179 418381 402793 411726 417756 401205 449628 411550 415963 409226 411616 402717 446552 411665 416659 413002 410753 411175 371969 350322 347061 350328 348569 339902 375057 355568 350508 352691 350222 343343 373208 349520 346765 347158 347937 340985 380224 353005 343816 342485 351019 345375 373701 350791 346338 345451 344418 343724 371254 357966 347545 350138 341789 338424 373600 342505 343568 351587 348491 345824 380946 350230 348378 345697 342716 343715
sum_num_searches_total=46490226
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=233081 220796 220536 226521 221431 216807 242210 225007 225256 224352 224449 220309 242983 220805 221832 221064 216306 219761 240782 223217 222207 226940 223054 220170 237710 222310 218370 223115 221959 218240 240339 226965 224584 222560 222386 223842 242426 221573 225066 222612 220142 216149 243806 219966 221026 221519 220299 220205 490916 454631 447875 447432 440594 452542 498662 460718 449397 449004 453092 453702 491658 452652 452565 447799 455282 446073 491473 454963 462970 454649 461171 450729 494347 452212 448402 451116 448877 445829 491401 464949 447555 457678 463827 445944 499499 457218 461789 454900 457237 447169 496135 457352 462674 458980 456465 457216 413334 389272 385884 389401 387343 377630 416974 394930 389534 391728 389125 381427 415000 388300 385406 385724 386412 378759 422336 392447 382080 381006 390082 383893 415122 389844 384253 383830 382836 381891 412513 397508 386170 389221 379756 376221 415031 381154 382118 390422 386728 384383 423019 389276 386914 383912 380685 381639
average_num_operations_total=358741
stdev_num_operations_total=8354
sum_num_operations_total=51658838
min_num_operations_total=216149
max_num_operations_total=499499
first_time_thread_terminate_by_thread=10000321 10000611 10000208 10000240 10000636 10000631 10000609 10000323 10000638 10000635 10000148 10000612 10000364 10000152 10000158 10000607 10000149 10000621 10000608 10000638 10000598 10000148 10000623 10000629 10000625 10000200 10000206 10000622 10000343 10000615 10000284 10000619 10000628 10000635 10000355 10000624 10000588 10000262 10000328 10000325 10000356 10000626 10000259 10000638 10000637 10000640 10000637 10000150 10000174 10000308 10000286 10000291 10000334 10000316 10000289 10000311 10000297 10000145 10000276 10000278 10000191 10000360 10000359 10000340 10000315 10000306 10000309 10000330 10000145 10000333 10000145 10000272 10000145 10000243 10000236 10000147 10000295 10000337 10000147 10000154 10000310 10000269 10000158 10000358 10000279 10000339 10000341 10000147 10000314 10000146 10000290 10000300 10000287 10000332 10000266 10000267 10000169 10000591 10000212 10000594 10000229 10000632 10000231 10000147 10000255 10000599 10000602 10000244 10000250 10000222 10000154 10000597 10000593 10000247 10000618 10000164 10000603 10000219 10000179 10000149 10000362 10000589 10000604 10000633 10000345 10000632 10000634 10000216 10000352 10000260 10000354 10000256 10000595 10000182 10000194 10000616 10000613 10000347 10000196 10000225 10000349 10000155 10000353 10000592
min_time_thread_terminate_total=10000145
max_time_thread_terminate_total=10000640
first_time_thread_start_by_thread=10 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 4 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 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 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 5 5
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=99997781850337
threads_final_size=9999598
final_keysum=99997781850337
final_size=9999598
validate_result=success
Validation OK.
Structural validation OK.

total_find=46490226
total_rq=0
total_inserts=2583318
total_deletes=2585294
total_updates=5168612
total_queries=46490226
total_ops=51658838
find_throughput=4649022
rq_throughput=0
update_throughput=516861
query_throughput=4649022
total_throughput=5165883


total find                    : 46490226
total rq                      : 0
total inserts                 : 2583318
total deletes                 : 2585294
total updates                 : 5168612
total queries                 : 46490226
total ops                     : 51658838
find throughput               : 4649022
rq throughput                 : 0
update throughput             : 516861
query throughput              : 4649022
total throughput              : 5165883

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=36.6658
PAPI_L3_TCM=11.2242
PAPI_TOT_CYC=77310.7
PAPI_TOT_INS=8506.97
garbage=232399919621461
total_execution_walltime=44.521s
REALTIME_MAIN_END_PERF_FORMAT=6992923.492056208
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 57041947
[slow htm]
[fallback]
total fallback commit         : 9823604
seconds global lock is held   : 2.18239
[time_cmd_output] time_elapsed_sec=44.70, faults_major=0, faults_minor=224529, mem_maxresident_kb=893504, user_cputime=6055.70, sys_cputime=10.79, percent_cpu=13570%
timeout=false
algorithm=brown_sigouin_int_avl_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=1