__step=000610 __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 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000610.txt __path_data=data/data000610.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6986211.928592686 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=200000 PREFILL_THREADS=48 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=48 WORK_THREADS=48 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 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.004s prefilling_size=100134 prefilling_elapsed_millis=1s finished prefilling to size 100196 for expected size 100000 keysum=10013108251, performing 66647929 updates; total_prefilling_elapsed_ms=1101 ms) pref_size=100196 pref_millis=1101 prefill_elapsed_ms=1132 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=3338 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6986213.233905788 REALTIME_END_PERF_FORMAT=6986223.234037238 ############################################################################### ################################## 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 938 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 250 469 849 1453 2311 3434 4697 6026 7474 8642 9577 9934 9833 8952 7743 6012 4368 3080 1978 1236 690 372 170 74 23 4 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 6 31 89 245 595 1188 2171 3368 4578 6306 7707 9220 10035 10714 10161 9474 7656 5656 4182 2720 1782 1008 574 266 125 42 7 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 500 938 1698 2906 4622 6868 9394 12052 14948 17284 19154 19868 19666 17904 15486 12024 8736 6160 3956 2472 1380 744 340 148 46 8 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 6 31 89 245 595 1188 2171 3368 4578 6306 7707 9220 10035 10714 10161 9474 7656 5656 4182 2720 1782 1008 574 266 125 42 7 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.97656 1.938 1.90512 1.85571 1.79525 1.74297 1.6839 1.64147 1.62015 1.57814 1.5541 1.51864 1.49492 1.4552 1.43247 1.38822 1.36327 1.35256 1.3211 1.31244 1.27913 1.26957 1.22849 1.21765 1.15541 1.08696 1.125 1 tree_stats_height=36 tree_stats_numInternals=99907 tree_stats_numLeaves=99908 tree_stats_numNodes=199815 tree_stats_numKeys=99908 tree_stats_avgDegreeInternal=2.00001 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=20.8843 tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16000 30016 54336 92992 147904 219776 300608 385664 478336 553088 612928 635776 629312 572928 495552 384768 279552 197120 126592 79104 44160 23808 10880 4736 1472 256 64 tree_stats_sizeInBytes=6394080 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=46707 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:67 4:1498 8:49905 16:370367 32:57658 64:104 128:55 256:39 512:47 1024:47 (2^01, 2^02]: 67 (2^02, 2^03]: 1498 (2^03, 2^04]: 49905 (2^04, 2^05]: 370367 (2^05, 2^06]: 57658 (2^06, 2^07]: 104 (2^07, 2^08]: 55 (2^08, 2^09]: 39 (2^09, 2^10]: 47 (2^10, 2^11]: 47 sum_limbo_reclamation_event_size_total=11202538 count_limbo_reclamation_event_size_by_thread=9996 9996 9995 9995 9995 9995 9996 9995 9996 9996 9995 9996 9996 9995 9996 9996 9997 9996 9995 9995 9995 9996 9995 9996 9995 9996 9996 9995 9996 9996 9996 9995 9996 9995 9995 9996 9996 9995 9996 9996 9996 9996 9995 9995 9995 9996 9994 9996 count_limbo_reclamation_event_size_total=479787 sum_limbo_reclamation_event_count_total=2081332 first_thread_announced_epoch_by_thread=93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 93414 sum_num_inserts_by_thread=993298 999926 1011563 1010140 1001890 988446 994492 1000996 1016070 1021580 1007566 999743 998050 1006670 1006626 1011527 1009346 1003517 996248 1003544 1013586 1016085 1004022 994476 992863 1001432 1012006 1008483 1005445 990197 998966 1002740 1015980 1017833 1004708 1000256 1000264 1001715 1010592 1017495 1013089 1005573 995964 1002239 1005510 1014774 1003404 998485 sum_num_inserts_total=48229420 sum_num_deletes_by_thread=994100 1002147 1009075 1009805 1003188 990173 994298 1000886 1016335 1019050 1008660 1001284 998296 1003930 1005835 1010436 1010810 1002985 994752 1004250 1014139 1014213 1004993 995461 991051 1002487 1012718 1008268 1003214 989784 997200 1004358 1016168 1018233 1005942 1000444 1003013 1001235 1012642 1017120 1011351 1002063 994849 1001837 1006753 1015052 1003458 997975 sum_num_deletes_total=48226316 sum_num_searches_by_thread=17876982 18013818 18189196 18164320 18040064 17803500 17905730 18008359 18295178 18362901 18148135 18033083 17986223 18074784 18108956 18196026 18209023 18069262 17934687 18093326 18235543 18256011 18058376 17899204 17861687 18025819 18240080 18147406 18074084 17825663 17951628 18042940 18281215 18322498 18087561 18007311 18002187 18076110 18228553 18315086 18215498 18071178 17913676 18029243 18113189 18260530 18052757 17952663 sum_num_searches_total=868061249 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=19864380 20015891 20209834 20184265 20045142 19782119 19894520 20010241 20327583 20403531 20164361 20034110 19982569 20085384 20121417 20217989 20229179 20075764 19925687 20101120 20263268 20286309 20067391 19889141 19845601 20029738 20264804 20164157 20082743 19805644 19947794 20050038 20313363 20358564 20098211 20008011 20005464 20079060 20251787 20349701 20239938 20078814 19904489 20033319 20125452 20290356 20059619 19949123 average_num_operations_total=20094103 stdev_num_operations_total=22546 sum_num_operations_total=964516985 min_num_operations_total=19782119 max_num_operations_total=20403531 first_time_thread_terminate_by_thread=10000130 10000130 10000131 10000130 10000131 10000130 10000130 10000131 10000131 10000130 10000131 10000131 10000131 10000131 10000130 10000130 10000131 10000130 10000131 10000131 10000130 10000130 10000131 10000131 10000130 10000130 10000131 10000130 10000131 10000130 10000130 10000131 10000131 10000130 10000130 10000131 10000131 10000131 10000130 10000130 10000131 10000130 10000131 10000131 10000130 10000130 10000131 10000131 min_time_thread_terminate_total=10000130 max_time_thread_terminate_total=10000131 first_time_thread_start_by_thread=4 4 4 4 3 4 4 4 3 3 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 3 3 4 4 3 4 4 4 4 4 4 4 4 4 4 4 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=9996371407 threads_final_size=99908 final_keysum=9996371407 final_size=99908 validate_result=success Validation OK. Structural validation OK. total_find=868061249 total_rq=0 total_inserts=48229420 total_deletes=48226316 total_updates=96455736 total_queries=868061249 total_ops=964516985 find_throughput=86806124 rq_throughput=0 update_throughput=9645573 query_throughput=86806124 total_throughput=96451698 total find : 868061249 total rq : 0 total inserts : 48229420 total deletes : 48226316 total updates : 96455736 total queries : 868061249 total ops : 964516985 find throughput : 86806124 rq throughput : 0 update throughput : 9645573 query throughput : 86806124 total throughput : 96451698 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=5.12091 PAPI_L3_TCM=0.000157105 PAPI_TOT_CYC=1361.85 PAPI_TOT_INS=336.593 garbage=43405467339967 total_execution_walltime=11.436s REALTIME_MAIN_END_PERF_FORMAT=6986223.380863619 [time_cmd_output] time_elapsed_sec=11.56, faults_major=0, faults_minor=76761, mem_maxresident_kb=309024, user_cputime=558.74, sys_cputime=3.19, percent_cpu=4858% 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=2