__step=000723 __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 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000723.txt __path_data=data/data000723.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6987625.709262667 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=50 DEL_FRAC=50 RQ=0 RQSIZE=0 MAXKEY=20000000 PREFILL_THREADS=96 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=96 WORK_THREADS=96 RQ_THREADS=0 distribution=0 INS_DEL_FRAC=50.0 50.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.038s prefilling_size=8811369 prefilling_elapsed_millis=1s prefilling_size=8991704 prefilling_elapsed_millis=1.1s prefilling_size=9142521 prefilling_elapsed_millis=1.201s prefilling_size=9270599 prefilling_elapsed_millis=1.301s prefilling_size=9378339 prefilling_elapsed_millis=1.401s prefilling_size=9468234 prefilling_elapsed_millis=1.501s prefilling_size=9544462 prefilling_elapsed_millis=1.601s prefilling_size=9610840 prefilling_elapsed_millis=1.701s prefilling_size=9663884 prefilling_elapsed_millis=1.802s prefilling_size=9711332 prefilling_elapsed_millis=1.902s prefilling_size=9751852 prefilling_elapsed_millis=2.002s prefilling_size=9785348 prefilling_elapsed_millis=2.102s prefilling_size=9816026 prefilling_elapsed_millis=2.202s finished prefilling to size 9816122 for expected size 10000000 keysum=98162517713072, performing 80169021 updates; total_prefilling_elapsed_ms=2304 ms) pref_size=9816122 pref_millis=2304 prefill_elapsed_ms=2325 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=1024 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6987628.228656754 REALTIME_END_PERF_FORMAT=6987638.228794652 ############################################################################### ################################## 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 (192 threads)... partitioned into 1012 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.135s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 506 993 1951 3747 7011 12737 22493 38033 62088 97671 147689 213926 296184 390237 490340 587099 671845 734097 769126 773176 749372 701381 636314 558434 475714 392680 314243 244784 185051 136189 98003 68346 46360 30423 18931 11550 6826 3995 2180 1102 580 323 160 61 32 17 4 2 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 6 19 35 155 483 1285 2981 6953 13978 26505 47653 81452 131668 202131 290134 393581 502353 609593 699068 765076 796980 797363 766448 714194 641154 558748 471117 383702 304517 233913 174375 127660 90332 62297 41915 26312 16274 9657 5810 3258 1624 837 486 259 90 47 30 6 3 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1012 1986 3902 7494 14022 25474 44986 76066 124176 195342 295378 427852 592368 780474 980680 1174198 1343690 1468194 1538252 1546352 1498744 1402762 1272628 1116868 951428 785360 628486 489568 370102 272378 196006 136692 92720 60846 37862 23100 13652 7990 4360 2204 1160 646 320 122 64 34 8 4 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 6 19 35 155 483 1285 2981 6953 13978 26505 47653 81452 131668 202131 290134 393581 502353 609593 699068 765076 796980 797363 766448 714194 641154 558748 471117 383702 304517 233913 174375 127660 90332 62297 41915 26312 16274 9657 5810 3258 1624 837 486 259 90 47 30 6 3 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 1.98828 1.98123 1.98238 1.96028 1.93555 1.90836 1.88298 1.84544 1.81624 1.78655 1.75605 1.72424 1.69226 1.65877 1.62826 1.59867 1.57217 1.54633 1.52386 1.50263 1.48461 1.46798 1.45362 1.4388 1.42594 1.41273 1.40013 1.38948 1.37799 1.36798 1.35981 1.34869 1.33916 1.32812 1.31113 1.30506 1.2955 1.29263 1.27284 1.25275 1.26316 1.27845 1.24768 1.19063 1.2623 1.26562 1.11765 1.25 1.25 1 tree_stats_height=59 tree_stats_numInternals=10004518 tree_stats_numLeaves=10004519 tree_stats_numNodes=20009037 tree_stats_numKeys=10004519 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=30.2555 tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16384 32384 63552 124864 239808 448704 815168 1439552 2434112 3973632 6250944 9452096 13691264 18955776 24975168 31381760 37574336 42998080 46982208 49224064 49483264 47959808 44888384 40724096 35739776 30445696 25131520 20111552 15666176 11843264 8716096 6272192 4374144 2967040 1947072 1211584 739200 436864 255680 139520 70528 37120 20672 10240 3904 2048 1088 256 128 64 tree_stats_sizeInBytes=640289184 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=5151 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:1 8:0 16:1 32:0 64:62 128:20803 256:373472 512:1659 (2^02, 2^03]: 1 (2^03, 2^04]: 0 (2^04, 2^05]: 1 (2^05, 2^06]: 0 (2^06, 2^07]: 62 (2^07, 2^08]: 20803 (2^08, 2^09]: 373472 (2^09, 2^10]: 1659 sum_limbo_reclamation_event_size_total=148655856 count_limbo_reclamation_event_size_by_thread=4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4123 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 4125 count_limbo_reclamation_event_size_total=395998 sum_limbo_reclamation_event_count_total=396191 first_thread_announced_epoch_by_thread=10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 10302 sum_num_inserts_by_thread=1542820 1546667 1558607 1558280 1553620 1541936 1547579 1552608 1562616 1558323 1545591 1545741 1548269 1555008 1557921 1562058 1557654 1549136 1538787 1544419 1553177 1556802 1545520 1534917 1537896 1546178 1557328 1557160 1548508 1541611 1548316 1548960 1562817 1566232 1548989 1540185 1547710 1552248 1557967 1562676 1553922 1551129 1539634 1542151 1553599 1555244 1543924 1535991 1549056 1546127 1558148 1555633 1546677 1542623 1544467 1547780 1559928 1561507 1550346 1542675 1546713 1555069 1558940 1558899 1552450 1545408 1538146 1550670 1551571 1560713 1555123 1545302 1548221 1556479 1560776 1559373 1546218 1546300 1549048 1552147 1566357 1567492 1555785 1550650 1555162 1562094 1560565 1563947 1558156 1543563 1542079 1555598 1560325 1558907 1557880 1545761 sum_num_inserts_total=148965285 sum_num_deletes_by_thread=1542833 1548858 1560134 1557101 1552348 1538521 1545070 1553862 1562277 1560729 1543549 1544108 1548711 1551849 1556939 1562020 1554146 1549451 1537041 1542564 1553121 1557248 1546965 1535101 1539748 1545407 1558817 1552491 1548961 1540675 1546822 1549783 1566929 1565718 1549054 1538395 1548425 1551983 1557270 1563664 1552742 1549816 1538530 1544727 1554019 1555994 1542756 1537108 1547142 1550345 1559110 1556024 1543857 1539856 1540694 1548693 1563224 1558739 1549077 1543928 1547640 1555136 1558997 1559301 1554916 1544006 1539385 1548355 1553753 1560273 1554318 1547754 1548891 1554629 1557704 1557961 1545559 1544514 1550157 1552083 1568604 1566923 1556796 1551024 1552787 1558218 1562111 1562251 1557388 1543270 1541473 1552598 1560072 1558952 1554398 1547149 sum_num_deletes_total=148934415 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3085653 3095525 3118741 3115381 3105968 3080457 3092649 3106470 3124893 3119052 3089140 3089849 3096980 3106857 3114860 3124078 3111800 3098587 3075828 3086983 3106298 3114050 3092485 3070018 3077644 3091585 3116145 3109651 3097469 3082286 3095138 3098743 3129746 3131950 3098043 3078580 3096135 3104231 3115237 3126340 3106664 3100945 3078164 3086878 3107618 3111238 3086680 3073099 3096198 3096472 3117258 3111657 3090534 3082479 3085161 3096473 3123152 3120246 3099423 3086603 3094353 3110205 3117937 3118200 3107366 3089414 3077531 3099025 3105324 3120986 3109441 3093056 3097112 3111108 3118480 3117334 3091777 3090814 3099205 3104230 3134961 3134415 3112581 3101674 3107949 3120312 3122676 3126198 3115544 3086833 3083552 3108196 3120397 3117859 3112278 3092910 average_num_operations_total=3103121 stdev_num_operations_total=1563 sum_num_operations_total=297899700 min_num_operations_total=3070018 max_num_operations_total=3134961 first_time_thread_terminate_by_thread=10000141 10000137 10000139 10000138 10000138 10000138 10000141 10000139 10000140 10000139 10000138 10000140 10000138 10000139 10000138 10000138 10000139 10000140 10000141 10000138 10000138 10000140 10000140 10000140 10000141 10000141 10000140 10000138 10000138 10000141 10000140 10000139 10000138 10000141 10000138 10000138 10000138 10000139 10000138 10000139 10000137 10000140 10000141 10000140 10000138 10000138 10000140 10000140 10000140 10000141 10000141 10000139 10000138 10000137 10000141 10000138 10000140 10000137 10000139 10000139 10000137 10000139 10000140 10000141 10000140 10000141 10000140 10000139 10000142 10000140 10000139 10000138 10000141 10000141 10000141 10000139 10000142 10000138 10000141 10000138 10000140 10000139 10000139 10000139 10000137 10000139 10000140 10000138 10000139 10000141 10000139 10000139 10000142 10000158 10000139 10000138 min_time_thread_terminate_total=10000137 max_time_thread_terminate_total=10000158 first_time_thread_start_by_thread=7 7 6 7 6 6 7 7 6 7 7 6 7 6 7 6 6 6 7 6 7 7 7 6 7 7 6 7 6 6 7 7 6 7 7 6 7 6 7 6 6 6 7 6 7 7 7 6 7 7 7 6 7 6 7 6 7 7 6 6 7 6 6 7 6 7 6 6 7 6 7 6 7 7 7 6 7 6 7 6 7 7 6 6 7 6 6 7 6 7 6 6 7 6 7 6 min_time_thread_start_total=6 max_time_thread_start_total=7 sum_duration_all_ops_total= threads_final_keysum=100046368954848 threads_final_size=10004519 final_keysum=100046368954848 final_size=10004519 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=148965285 total_deletes=148934415 total_updates=297899700 total_queries=0 total_ops=297899700 find_throughput=0 rq_throughput=0 update_throughput=29789970 query_throughput=0 total_throughput=29789970 total find : 0 total rq : 0 total inserts : 148965285 total deletes : 148934415 total updates : 297899700 total queries : 0 total ops : 297899700 find throughput : 0 rq throughput : 0 update throughput : 29789970 query throughput : 0 total throughput : 29789970 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=33.819 PAPI_L3_TCM=13.3426 PAPI_TOT_CYC=8945.41 PAPI_TOT_INS=1266.8 garbage=0 total_execution_walltime=12.83s REALTIME_MAIN_END_PERF_FORMAT=6987638.540639726 [time_cmd_output] time_elapsed_sec=12.99, faults_major=0, faults_minor=241440, mem_maxresident_kb=963840, user_cputime=1207.73, sys_cputime=6.58, percent_cpu=9345% 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