__step=000054
__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=data000054.txt
__path_data=data/data000054.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992923.720837782
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.077s
prefilling_size=243696 prefilling_elapsed_millis=1s
prefilling_size=267648 prefilling_elapsed_millis=1.1s
prefilling_size=291657 prefilling_elapsed_millis=1.201s
prefilling_size=315462 prefilling_elapsed_millis=1.301s
prefilling_size=339209 prefilling_elapsed_millis=1.401s
prefilling_size=362909 prefilling_elapsed_millis=1.501s
prefilling_size=386446 prefilling_elapsed_millis=1.601s
prefilling_size=410061 prefilling_elapsed_millis=1.701s
prefilling_size=433550 prefilling_elapsed_millis=1.802s
prefilling_size=457079 prefilling_elapsed_millis=1.902s
prefilling_size=480551 prefilling_elapsed_millis=2.002s
prefilling_size=503990 prefilling_elapsed_millis=2.102s
prefilling_size=527443 prefilling_elapsed_millis=2.202s
prefilling_size=550666 prefilling_elapsed_millis=2.303s
prefilling_size=573974 prefilling_elapsed_millis=2.403s
prefilling_size=597165 prefilling_elapsed_millis=2.503s
prefilling_size=620182 prefilling_elapsed_millis=2.603s
prefilling_size=643251 prefilling_elapsed_millis=2.704s
prefilling_size=666167 prefilling_elapsed_millis=2.804s
prefilling_size=689023 prefilling_elapsed_millis=2.904s
prefilling_size=711816 prefilling_elapsed_millis=3.004s
prefilling_size=734547 prefilling_elapsed_millis=3.104s
prefilling_size=757367 prefilling_elapsed_millis=3.205s
prefilling_size=780146 prefilling_elapsed_millis=3.305s
prefilling_size=802743 prefilling_elapsed_millis=3.405s
prefilling_size=825372 prefilling_elapsed_millis=3.505s
prefilling_size=847860 prefilling_elapsed_millis=3.606s
prefilling_size=870289 prefilling_elapsed_millis=3.706s
prefilling_size=892577 prefilling_elapsed_millis=3.806s
prefilling_size=914899 prefilling_elapsed_millis=3.906s
prefilling_size=937137 prefilling_elapsed_millis=4.007s
prefilling_size=959152 prefilling_elapsed_millis=4.107s
prefilling_size=981291 prefilling_elapsed_millis=4.207s
prefilling_size=1003386 prefilling_elapsed_millis=4.307s
prefilling_size=1025425 prefilling_elapsed_millis=4.407s
prefilling_size=1047414 prefilling_elapsed_millis=4.508s
prefilling_size=1069339 prefilling_elapsed_millis=4.608s
prefilling_size=1091146 prefilling_elapsed_millis=4.708s
prefilling_size=1112755 prefilling_elapsed_millis=4.808s
prefilling_size=1134585 prefilling_elapsed_millis=4.909s
prefilling_size=1156402 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 8843430 keys with 144 threads...
tid=0 thread_prefill_amount=8328 percent_done_estimate=13.6 elapsed_s=6 est_remaining_s=88 / 94
tid=0 thread_prefill_amount=9716 percent_done_estimate=15.8 elapsed_s=7 est_remaining_s=84 / 91
tid=0 thread_prefill_amount=11104 percent_done_estimate=18.1 elapsed_s=8 est_remaining_s=82 / 90
tid=0 thread_prefill_amount=12492 percent_done_estimate=20.3 elapsed_s=9 est_remaining_s=79 / 88
tid=0 thread_prefill_amount=13880 percent_done_estimate=22.6 elapsed_s=10 est_remaining_s=76 / 86
tid=0 thread_prefill_amount=15268 percent_done_estimate=24.9 elapsed_s=11 est_remaining_s=74 / 85
tid=0 thread_prefill_amount=16656 percent_done_estimate=27.1 elapsed_s=12 est_remaining_s=71 / 83
tid=0 thread_prefill_amount=18044 percent_done_estimate=29.4 elapsed_s=13 est_remaining_s=69 / 82
tid=0 thread_prefill_amount=19432 percent_done_estimate=31.6 elapsed_s=14 est_remaining_s=67 / 80
tid=0 thread_prefill_amount=20820 percent_done_estimate=33.9 elapsed_s=15 est_remaining_s=64 / 79
tid=0 thread_prefill_amount=22208 percent_done_estimate=36.2 elapsed_s=16 est_remaining_s=62 / 78
tid=0 thread_prefill_amount=23596 percent_done_estimate=38.4 elapsed_s=17 est_remaining_s=60 / 77
tid=0 thread_prefill_amount=24984 percent_done_estimate=40.7 elapsed_s=18 est_remaining_s=58 / 75
tid=0 thread_prefill_amount=26372 percent_done_estimate=42.9 elapsed_s=19 est_remaining_s=56 / 74
tid=0 thread_prefill_amount=27760 percent_done_estimate=45.2 elapsed_s=20 est_remaining_s=53 / 73
tid=0 thread_prefill_amount=29148 percent_done_estimate=47.5 elapsed_s=20 est_remaining_s=51 / 72
tid=0 thread_prefill_amount=30536 percent_done_estimate=49.7 elapsed_s=21 est_remaining_s=49 / 70
tid=0 thread_prefill_amount=31924 percent_done_estimate=52.0 elapsed_s=22 est_remaining_s=47 / 69
tid=0 thread_prefill_amount=33312 percent_done_estimate=54.2 elapsed_s=23 est_remaining_s=44 / 68
tid=0 thread_prefill_amount=34700 percent_done_estimate=56.5 elapsed_s=24 est_remaining_s=42 / 67
tid=0 thread_prefill_amount=36088 percent_done_estimate=58.8 elapsed_s=25 est_remaining_s=40 / 65
tid=0 thread_prefill_amount=37476 percent_done_estimate=61.0 elapsed_s=26 est_remaining_s=38 / 64
tid=0 thread_prefill_amount=38864 percent_done_estimate=63.3 elapsed_s=27 est_remaining_s=36 / 63
tid=0 thread_prefill_amount=40252 percent_done_estimate=65.5 elapsed_s=28 est_remaining_s=33 / 62
tid=0 thread_prefill_amount=41640 percent_done_estimate=67.8 elapsed_s=29 est_remaining_s=31 / 60
tid=0 thread_prefill_amount=43028 percent_done_estimate=70.1 elapsed_s=30 est_remaining_s=29 / 59
tid=0 thread_prefill_amount=44416 percent_done_estimate=72.3 elapsed_s=31 est_remaining_s=27 / 58
tid=0 thread_prefill_amount=45804 percent_done_estimate=74.6 elapsed_s=32 est_remaining_s=25 / 57
tid=0 thread_prefill_amount=47192 percent_done_estimate=76.8 elapsed_s=33 est_remaining_s=22 / 55
tid=0 thread_prefill_amount=48580 percent_done_estimate=79.1 elapsed_s=34 est_remaining_s=20 / 54
tid=0 thread_prefill_amount=49968 percent_done_estimate=81.4 elapsed_s=35 est_remaining_s=18 / 53
tid=0 thread_prefill_amount=51356 percent_done_estimate=83.6 elapsed_s=36 est_remaining_s=16 / 52
tid=0 thread_prefill_amount=52744 percent_done_estimate=85.9 elapsed_s=37 est_remaining_s=14 / 51
tid=0 thread_prefill_amount=54132 percent_done_estimate=88.1 elapsed_s=38 est_remaining_s=11 / 49
tid=0 thread_prefill_amount=55520 percent_done_estimate=90.4 elapsed_s=38 est_remaining_s=9 / 47
tid=0 thread_prefill_amount=56908 percent_done_estimate=92.7 elapsed_s=39 est_remaining_s=7 / 45
tid=0 thread_prefill_amount=58296 percent_done_estimate=94.9 elapsed_s=39 est_remaining_s=5 / 44
tid=0 thread_prefill_amount=59684 percent_done_estimate=97.2 elapsed_s=39 est_remaining_s=3 / 42
tid=0 thread_prefill_amount=61072 percent_done_estimate=99.4 elapsed_s=40 est_remaining_s=0 / 40
tid=0 thread_prefill_amount=62460 percent_done_estimate=101.7 elapsed_s=40 est_remaining_s=-2 / 39
tid=0 thread_prefill_amount=63848 percent_done_estimate=104.0 elapsed_s=40 est_remaining_s=-3 / 37
tid=0 thread_prefill_amount=65236 percent_done_estimate=106.2 elapsed_s=40 est_remaining_s=-5 / 35
tid=0 thread_prefill_amount=66624 percent_done_estimate=108.5 elapsed_s=41 est_remaining_s=-7 / 33
tid=0 thread_prefill_amount=68012 percent_done_estimate=110.7 elapsed_s=41 est_remaining_s=-9 / 32
timing_elapsed 35.3112s
prefilling_size=10000000 prefilling_elapsed_millis=35.395s
finished prefilling to size 10000000 for expected size 10000000 keysum=100018276618764, performing 15127134 updates; total_prefilling_elapsed_ms=35395 ms)
pref_size=10000000
pref_millis=35395
prefill_elapsed_ms=35395
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6992964.504167725
REALTIME_END_PERF_FORMAT=6992974.504279692
###############################################################################
################################## 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 131072 262053 519813 966940 1440979 1393629 720877 159295 10278 47 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 88 3894 62820 404394 1157384 1522500 895949 204135 12832 58
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262141 523707 1029760 1845373 2551013 2243377 1055244 214413 12879 58
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262141 523707 1029760 1845373 2551013 2243377 1055244 214413 12879 58
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99998 1.99814 1.97373 1.85305 1.60152 1.3331 1.14905 1.05223 1.01213 1.00085 1
tree_stats_height=28
tree_stats_numInternals=5736054
tree_stats_numLeaves=4264054
tree_stats_numNodes=10000108
tree_stats_numKeys=10000108
tree_stats_avgDegreeInternal=1.74338
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.4264
tree_stats_avgKeyDepth=21.6952
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=52847
first_thread_announced_epoch_by_thread=958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958 958
sum_num_inserts_by_thread=13492 13198 13638 13493 13060 12893 13059 12917 13391 13228 12843 12829 13270 12943 13397 13438 13202 12858 13048 13044 13188 13034 12985 12640 13476 13049 13368 13390 13081 13508 13362 12902 13312 13414 12937 13109 12826 13057 13596 13401 12980 13167 13309 12955 12942 12948 13040 12639 21472 20408 21006 20825 20077 19962 20836 20490 20601 20552 20402 20666 20918 20573 21036 20242 20817 19953 20417 20396 20488 20473 20319 20449 21329 20448 20127 20917 20386 19967 20503 20398 20602 20628 20282 20426 20345 20604 21034 20544 20126 20498 20587 20653 20519 20377 19926 20067 18126 16695 17536 17271 17202 17095 17355 17379 17439 17450 17094 17208 17455 17242 17506 17533 17348 17422 17309 17151 17219 17331 16850 17081 18178 16699 17566 17498 17233 16708 17588 16846 17226 17654 17124 16833 17626 17199 17457 17215 17076 17611 17543 17293 17207 17197 17428 16800
sum_num_inserts_total=2445629
sum_num_deletes_by_thread=13419 13121 13429 13339 13177 12927 13279 13087 13161 13149 12899 12881 13194 13013 13473 13309 13234 12951 12990 13172 13275 12825 13318 12487 13531 13199 13377 13439 13247 13123 13304 12985 13456 13396 12953 12819 12984 13470 13356 13309 13237 13134 13604 12868 13045 12874 13074 12610 21430 20177 20980 21084 20326 20227 20814 20463 20633 20406 20113 20527 20547 20658 20966 20410 20456 20016 20186 20227 20285 20382 20339 20194 21473 20708 20593 20700 19897 19894 20371 20916 20503 20489 20249 20718 20565 20516 20848 20399 20570 20357 20430 20636 20684 20483 19907 19968 17943 16698 17354 17129 17374 17123 17406 17327 17591 17482 17139 17237 17437 17136 17854 17336 17251 17380 17416 16911 17387 17101 16688 17154 18286 17011 17774 17504 17045 16672 17450 16906 17619 17493 17222 17131 17489 17353 17498 17080 17047 17498 17440 17230 17136 17117 17446 16679
sum_num_deletes_total=2445203
sum_num_searches_by_thread=240610 236684 242773 240378 234074 233882 235401 234637 240229 236324 231357 234575 236316 231791 238918 238310 235426 234462 236213 236642 236799 231086 236593 226219 241743 236027 238718 243773 238538 239947 239122 231946 240280 240934 235453 234391 232049 236195 243260 237276 234978 238413 242270 234071 231871 236837 234926 227823 382482 364831 376359 374825 364953 361788 371239 369062 373345 371887 366517 370083 375619 372026 376316 366627 372830 358605 364505 365965 364395 369621 364572 363302 384111 373099 367754 373158 362217 361027 365950 369896 369828 370501 365065 367277 370057 369146 374891 368688 364885 366980 370786 376828 369019 369575 359152 359424 322445 300465 315569 308572 312808 307999 314049 311300 312892 311572 308699 312758 315392 310483 316590 313902 311027 311769 311927 305552 312351 310106 303080 307885 328852 304596 319229 316277 307393 299234 314304 306890 312628 313885 309935 305204 315404 312237 315966 309140 304357 316119 318707 311094 308817 307515 314418 302859
sum_num_searches_total=44005860
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=267521 263003 269840 267210 260311 259702 261739 260641 266781 262701 257099 260285 262780 257747 265788 265057 261862 260271 262251 262858 263262 256945 262896 251346 268750 262275 265463 270602 264866 266578 265788 257833 267048 267744 261343 260319 257859 262722 270212 263986 261195 264714 269183 259894 257858 262659 261040 253072 425384 405416 418345 416734 405356 401977 412889 410015 414579 412845 407032 411276 417084 413257 418318 407279 414103 398574 405108 406588 405168 410476 405230 403945 426913 414255 408474 414775 402500 400888 406824 411210 410933 411618 405596 408421 410967 410266 416773 409631 405581 407835 411803 418117 410222 410435 398985 399459 358514 333858 350459 342972 347384 342217 348810 346006 347922 346504 342932 347203 350284 344861 351950 348771 345626 346571 346652 339614 346957 344538 336618 342120 365316 338306 354569 351279 341671 332614 349342 340642 347473 349032 344281 339168 350519 346789 350921 343435 338480 351228 353690 345617 343160 341829 349292 336338
average_num_operations_total=339560
stdev_num_operations_total=5078
sum_num_operations_total=48896692
min_num_operations_total=251346
max_num_operations_total=426913
first_time_thread_terminate_by_thread=10000604 10000191 10000200 10000111 10000603 10000315 10000318 10000277 10000128 10000111 10000114 10000349 10000611 10000342 10000610 10000352 10000295 10000613 10000353 10000114 10000111 10000269 10000617 10000195 10000334 10000619 10000184 10000302 10000602 10000347 10000341 10000252 10000338 10000332 10000114 10000620 10000615 10000350 10000263 10000293 10000164 10000605 10000322 10000320 10000602 10000113 10000616 10000265 10000149 10000346 10000612 10000129 10000112 10000122 10000286 10000219 10000308 10000216 10000133 10000605 10000112 10000112 10000323 10000112 10000257 10000285 10000152 10000280 10000225 10000232 10000112 10000136 10000288 10000310 10000304 10000279 10000113 10000206 10000201 10000283 10000210 10000213 10000222 10000618 10000112 10000112 10000113 10000335 10000131 10000306 10000147 10000281 10000607 10000204 10000259 10000181 10000139 10000123 10000290 10000244 10000111 10000601 10000231 10000190 10000326 10000254 10000247 10000609 10000158 10000325 10000300 10000238 10000110 10000615 10000243 10000272 10000340 10000235 10000327 10000111 10000296 10000229 10000246 10000256 10000176 10000330 10000275 10000172 10000111 10000177 10000167 10000344 10000313 10000311 10000144 10000239 10000113 10000267 10000111 10000289 10000188 10000232 10000215 10000329
min_time_thread_terminate_total=10000110
max_time_thread_terminate_total=10000620
first_time_thread_start_by_thread=9 4 5 4 5 4 5 5 4 5 4 5 5 5 4 4 4 5 4 4 5 4 5 4 4 4 5 4 5 4 5 5 4 5 4 5 5 5 4 4 4 5 4 4 5 4 5 4 5 6 5 6 6 5 5 5 6 6 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 6 5 6 6 5 5 5 6 6 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 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
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=
threads_final_keysum=100025936192863
threads_final_size=10000108
final_keysum=100025936192863
final_size=10000108
validate_result=success
Validation OK.
Structural validation OK.
total_find=44005860
total_rq=0
total_inserts=2445629
total_deletes=2445203
total_updates=4890832
total_queries=44005860
total_ops=48896692
find_throughput=4400586
rq_throughput=0
update_throughput=489083
query_throughput=4400586
total_throughput=4889669
total find : 44005860
total rq : 0
total inserts : 2445629
total deletes : 2445203
total updates : 4890832
total queries : 44005860
total ops : 48896692
find throughput : 4400586
rq throughput : 0
update throughput : 489083
query throughput : 4400586
total throughput : 4889669
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=37.6517
PAPI_L3_TCM=11.5071
PAPI_TOT_CYC=81727.5
PAPI_TOT_INS=9000.9
garbage=214855463515204
total_execution_walltime=50.917s
REALTIME_MAIN_END_PERF_FORMAT=6992974.639145131
Hybrid noREC system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 52913207
[slow htm]
[fallback]
total fallback commit : 11110620
seconds global lock is held : 2.41719
[time_cmd_output] time_elapsed_sec=51.09, faults_major=0, faults_minor=223578, mem_maxresident_kb=889876, user_cputime=7097.41, sys_cputime=11.20, percent_cpu=13913%
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=2