__step=000499 __cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 60 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" ./natarajan_ext_bst_lf.debra -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=data000499.txt __path_data=data/data000499.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6984911.219022078 binary=./natarajan_ext_bst_lf.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=natarajan_ext_bst_lf 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 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.019s prefilling_size=999940 prefilling_elapsed_millis=1.001s finished prefilling to size 999933 for expected size 1000000 keysum=1000128405126, performing 48563240 updates; total_prefilling_elapsed_ms=1103 ms) pref_size=999933 pref_millis=1103 prefill_elapsed_ms=1126 6node_tIxPvE_object_size=32 6node_tIxPvE_allocated_count=0 6node_tIxPvE_allocated_size=0MB 6node_tIxPvE_get_from_pool=0 6node_tIxPvE_deallocated=0 6node_tIxPvE_limbo_count=0 6node_tIxPvE_limbo_details=0 0 0 global_epoch_counter=767 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6984912.548416291 REALTIME_END_PERF_FORMAT=6984922.548553619 ############################################################################### ################################## TIME IS UP ################################# ############################################################################### joining threads... ############################################################################### ################################# END RUNNING ################################# ############################################################################### 10s PRODUCING OUTPUT computing tree_stats in PARALLEL... bounded depth BFS to partition into subtrees for parallel computation (192 threads)... partitioned into 994 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.04s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 254 497 944 1779 3225 5469 8865 13635 20157 28604 38642 49808 61366 71859 80012 84424 85294 82690 76191 67486 57290 45983 35196 25890 18433 12910 8663 5638 3588 2088 1217 693 358 179 84 44 24 22 16 8 2 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 2 11 50 109 333 981 2073 4095 7113 11710 18566 27476 38250 50873 63706 75600 83554 87898 89189 84896 77682 68597 56770 44502 33347 23956 17157 11688 7688 5088 2959 1741 1028 537 274 124 64 26 28 24 14 3 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 508 994 1888 3558 6450 10938 17730 27270 40314 57208 77284 99616 122732 143718 160024 168848 170588 165380 152382 134972 114580 91966 70392 51780 36866 25820 17326 11276 7176 4176 2434 1386 716 358 168 88 48 44 32 16 4 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 2 11 50 109 333 981 2073 4095 7113 11710 18566 27476 38250 50873 63706 75600 83554 87898 89189 84896 77682 68597 56770 44502 33347 23956 17157 11688 7688 5088 2959 1741 1028 537 274 124 64 26 28 24 14 3 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99219 1.97835 1.9497 1.94227 1.90641 1.84791 1.81048 1.76904 1.73916 1.70953 1.67546 1.64448 1.61603 1.5855 1.55673 1.52757 1.50515 1.48474 1.4607 1.44287 1.42446 1.40132 1.38271 1.3678 1.35599 1.35019 1.33552 1.32541 1.3182 1.29097 1.29143 1.28472 1.2583 1.25 1.23464 1.2619 1.27273 1.45833 1.36364 1.25 1.125 1.25 1 tree_stats_height=51 tree_stats_numInternals=999783 tree_stats_numLeaves=999784 tree_stats_numNodes=1999567 tree_stats_numKeys=999784 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=25.901 tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16256 31808 60416 113856 206400 350016 567360 872640 1290048 1830656 2473088 3187712 3927424 4598976 5120768 5403136 5458816 5292160 4876224 4319104 3666560 2942912 2252544 1656960 1179712 826240 554432 360832 229632 133632 77888 44352 22912 11456 5376 2816 1536 1408 1024 512 128 64 tree_stats_sizeInBytes=63986144 6node_tIxPvE_object_size=32 6node_tIxPvE_allocated_count=0 6node_tIxPvE_allocated_size=0MB 6node_tIxPvE_get_from_pool=0 6node_tIxPvE_deallocated=0 6node_tIxPvE_limbo_count=0 6node_tIxPvE_limbo_details=0 0 0 global_epoch_counter=12252 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:74 16:42819 32:874892 64:41928 128:95 (2^03, 2^04]: 74 (2^04, 2^05]: 42819 (2^05, 2^06]: 874892 (2^06, 2^07]: 41928 (2^07, 2^08]: 95 sum_limbo_reclamation_event_size_total=44515106 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 count_limbo_reclamation_event_size_total=959808 sum_limbo_reclamation_event_count_total=1102560 first_thread_announced_epoch_by_thread=24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 24504 sum_num_inserts_by_thread=526201 531694 536051 536129 531423 524494 527021 530041 539051 538697 532262 529385 528299 532110 537324 537893 537976 532742 527222 531728 536555 538522 531276 527284 525313 530200 535998 536073 528450 524578 527409 530740 538470 539664 532368 527699 527421 531403 536410 537820 533160 529350 528445 532032 536397 537262 530830 525977 528499 533430 537524 537808 531408 530486 529055 533963 538313 539716 532937 531611 530260 533782 538632 536444 533172 526918 526743 530831 536225 536653 532514 530057 528652 531972 537802 537093 531485 529072 527396 533791 541989 541467 536023 533116 530371 531337 537113 535330 531777 526734 526401 531173 537160 539912 534672 532255 sum_num_inserts_total=51145923 sum_num_deletes_by_thread=525973 529832 537329 535383 531720 524365 525884 530451 538795 539070 531950 529143 530021 532605 538335 540843 537337 532447 527469 530439 536121 538494 530453 526755 525121 529740 535209 535411 529569 524187 526490 528989 538388 539574 530066 526458 526687 530263 535442 538648 533005 528960 527776 531401 535230 538693 529917 525800 529105 532612 539232 537722 532652 531353 530446 534209 540291 538976 534577 528718 530338 533515 537495 537608 534498 528819 526680 529576 535789 539452 531746 530931 529114 532329 537591 536638 532535 526487 529233 534339 540989 542049 536725 532828 531130 533310 536332 536624 531013 524836 527488 531155 536364 538196 534251 531629 sum_num_deletes_total=51141763 sum_num_searches_by_thread=9473253 9550864 9654024 9634520 9565549 9435042 9481912 9541993 9678595 9711230 9574533 9523396 9539887 9594521 9663499 9684505 9659169 9591083 9500315 9578835 9638730 9690472 9551565 9480407 9459264 9537709 9647738 9648302 9521963 9426401 9483668 9559063 9671662 9700897 9574304 9493616 9496013 9575572 9646587 9660061 9589145 9517872 9490429 9566951 9639545 9679958 9556148 9466260 9528741 9592960 9703587 9668095 9572805 9556396 9549427 9617868 9711726 9696418 9613654 9539817 9523796 9608464 9678700 9660361 9612643 9495790 9471669 9542337 9659879 9672116 9563366 9535593 9513235 9577033 9684743 9660430 9567175 9512032 9510518 9595871 9737520 9744068 9653782 9585311 9542165 9594895 9651749 9656174 9561309 9459801 9475260 9550360 9678781 9693294 9619727 9564972 sum_num_searches_total=920373440 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=10525427 10612390 10727404 10706032 10628692 10483901 10534817 10602485 10756441 10788997 10638745 10581924 10598207 10659236 10739158 10763241 10734482 10656272 10555006 10641002 10711406 10767488 10613294 10534446 10509698 10597649 10718945 10719786 10579982 10475166 10537567 10618792 10748520 10780135 10636738 10547773 10550121 10637238 10718439 10736529 10655310 10576182 10546650 10630384 10711172 10755913 10616895 10518037 10586345 10659002 10780343 10743625 10636865 10618235 10608928 10686040 10790330 10775110 10681168 10600146 10584394 10675761 10754827 10734413 10680313 10551527 10525092 10602744 10731893 10748221 10627626 10596581 10571001 10641334 10760136 10734161 10631195 10567591 10567147 10664001 10820498 10827584 10726530 10651255 10603666 10659542 10725194 10728128 10624099 10511371 10529149 10612688 10752305 10771402 10688650 10628856 average_num_operations_total=10652720 stdev_num_operations_total=8772 sum_num_operations_total=1022661126 min_num_operations_total=10475166 max_num_operations_total=10827584 first_time_thread_terminate_by_thread=10000138 10000138 10000136 10000137 10000137 10000137 10000137 10000136 10000136 10000138 10000136 10000138 10000138 10000136 10000136 10000138 10000136 10000137 10000136 10000136 10000138 10000137 10000137 10000136 10000138 10000136 10000136 10000137 10000137 10000138 10000137 10000136 10000136 10000138 10000137 10000138 10000136 10000136 10000136 10000138 10000136 10000137 10000136 10000136 10000138 10000137 10000137 10000136 10000137 10000136 10000137 10000138 10000137 10000136 10000137 10000137 10000138 10000137 10000137 10000137 10000137 10000136 10000136 10000137 10000137 10000137 10000137 10000139 10000137 10000137 10000137 10000136 10000137 10000138 10000137 10000138 10000137 10000136 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000136 10000137 10000137 10000137 10000137 10000137 10000138 10000137 10000137 10000137 10000137 min_time_thread_terminate_total=10000136 max_time_thread_terminate_total=10000139 first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 4 3 4 3 3 4 4 3 3 3 3 3 4 4 3 4 3 3 3 3 3 3 3 3 4 3 4 3 3 4 4 3 3 3 3 3 4 4 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=999643482869 threads_final_size=999784 final_keysum=999643482869 final_size=999784 validate_result=success Validation OK. Structural validation OK. total_find=920373440 total_rq=0 total_inserts=51145923 total_deletes=51141763 total_updates=102287686 total_queries=920373440 total_ops=1022661126 find_throughput=92037344 rq_throughput=0 update_throughput=10228768 query_throughput=92037344 total_throughput=102266112 total find : 920373440 total rq : 0 total inserts : 51145923 total deletes : 51141763 total updates : 102287686 total queries : 920373440 total ops : 1022661126 find throughput : 92037344 rq throughput : 0 update throughput : 10228768 query throughput : 92037344 total throughput : 102266112 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=12.3501 PAPI_L3_TCM=2.06441 PAPI_TOT_CYC=2612.68 PAPI_TOT_INS=378.234 garbage=460228752842808 total_execution_walltime=11.5s REALTIME_MAIN_END_PERF_FORMAT=6984922.733577923 [time_cmd_output] time_elapsed_sec=11.64, faults_major=0, faults_minor=95277, mem_maxresident_kb=379180, user_cputime=1093.28, sys_cputime=2.29, percent_cpu=9407% timeout=false algorithm=natarajan_ext_bst_lf.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1