__step=000259 __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" ./ellen_ext_bst_lf.debra -nwork 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000259.txt __path_data=data/data000259.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6981907.914041958 binary=./ellen_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=ellen_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=0.5 DEL_FRAC=0.5 RQ=0 RQSIZE=0 MAXKEY=200000 PREFILL_THREADS=96 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=96 WORK_THREADS=96 RQ_THREADS=0 distribution=0 INS_DEL_FRAC=0.5 0.5 PREFILL_TYPE=PREFILL_HYBRID PREFILL_HYBRID_MIN_MS=1000 PREFILL_HYBRID_MAX_MS=5000 sizes: node=40 descriptor=64 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.011s prefilling_size=100360 prefilling_elapsed_millis=1.001s finished prefilling to size 100378 for expected size 100000 keysum=10048906598, performing 32797620 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100378 pref_millis=1102 prefill_elapsed_ms=1126 6node_tIxPvE_object_size=40 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=883 6info_tIxPvE_object_size=64 6info_tIxPvE_allocated_count=0 6info_tIxPvE_allocated_size=0MB 6info_tIxPvE_get_from_pool=0 6info_tIxPvE_deallocated=0 6info_tIxPvE_limbo_count=0 6info_tIxPvE_limbo_details=0 0 0 global_epoch_counter=0 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6981909.228321826 REALTIME_END_PERF_FORMAT=6981919.228460967 ############################################################################### ################################## 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 944 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.04s tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 63 124 246 472 889 1568 2476 3609 4962 6300 7481 8460 9244 9528 9439 8801 7797 6287 4660 3043 1987 1177 653 338 163 64 35 9 1 0 tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 1 2 2 20 55 210 660 1343 2256 3624 5119 6502 7676 8960 9617 10077 9805 9307 7914 6277 4099 2797 1701 968 513 262 93 61 17 2 tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 126 248 492 944 1778 3136 4952 7218 9924 12600 14962 16920 18488 19056 18878 17602 15594 12574 9320 6086 3974 2354 1306 676 326 128 70 18 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 1 2 2 20 55 210 660 1343 2256 3624 5119 6502 7676 8960 9617 10077 9805 9307 7914 6277 4099 2797 1701 968 513 262 93 61 17 2 tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 1.98438 1.98413 1.99194 1.95935 1.94174 1.88189 1.78954 1.7288 1.68745 1.63482 1.59373 1.56543 1.54634 1.51536 1.49533 1.4662 1.44296 1.40317 1.37061 1.3265 1.32649 1.29618 1.2774 1.25881 1.24112 1.19632 1.27344 1.12857 1.05556 1 tree_stats_height=38 tree_stats_numInternals=99941 tree_stats_numLeaves=99942 tree_stats_numNodes=199883 tree_stats_numKeys=99940 tree_stats_avgDegreeInternal=2.00001 tree_stats_avgDegreeLeaves=0.99998 tree_stats_avgDegree=1.49999 tree_stats_avgKeyDepth=22.8403 tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 5040 9920 19680 37760 71120 125440 198080 288720 396960 504000 598480 676800 739520 762240 755120 704080 623760 502960 372800 243440 158960 94160 52240 27040 13040 5120 2800 720 80 tree_stats_sizeInBytes=7995320 6node_tIxPvE_object_size=40 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=20715 6info_tIxPvE_object_size=64 6info_tIxPvE_allocated_count=0 6info_tIxPvE_allocated_size=0MB 6info_tIxPvE_get_from_pool=0 6info_tIxPvE_deallocated=0 6info_tIxPvE_limbo_count=0 6info_tIxPvE_limbo_details=0 0 0 global_epoch_counter=0 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:31314 2:183618 4:494162 8:235165 16:6487 [2^00, 2^01]: 31314 (2^01, 2^02]: 183618 (2^02, 2^03]: 494162 (2^03, 2^04]: 235165 (2^04, 2^05]: 6487 sum_limbo_reclamation_event_size_total=5576319 count_limbo_reclamation_event_size_by_thread=9892 9900 9918 9934 9920 9890 9900 9914 9892 9900 9916 9890 9918 9896 9916 9914 9922 9890 9902 9926 9898 9922 9912 9896 9888 9926 9906 9904 9864 9896 9870 9876 9924 9914 9886 9912 9914 9890 9908 9922 9924 9920 9884 9886 9894 9910 9910 9896 9888 9904 9922 9912 9918 9914 9884 9916 9904 9928 9880 9866 9914 9876 9902 9912 9894 9896 9886 9914 9898 9900 9900 9896 9876 9906 9902 9914 9910 9914 9872 9910 9912 9908 9926 9902 9892 9906 9910 9916 9898 9914 9898 9906 9916 9908 9910 9894 count_limbo_reclamation_event_size_total=950746 sum_limbo_reclamation_event_count_total=3807788 first_thread_announced_epoch_by_thread=41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41430 41428 41430 41430 41430 41430 41430 41430 sum_num_inserts_by_thread=91352 92492 93697 93467 92898 90711 92334 92353 93974 93960 92811 92826 92685 92790 93248 93713 93766 92114 91337 92393 93060 93921 92729 91183 91373 91965 92714 92329 92863 91800 91743 92359 94071 93384 92461 92362 91963 93119 94295 94176 93430 91996 91131 92602 92889 93700 92522 91486 92941 92986 94807 93377 92639 92725 92612 92817 93751 93591 92432 92838 92343 93311 93522 93261 92174 91557 91938 93380 94348 93795 93206 91922 91121 92597 94214 93908 93079 92692 92016 92744 93139 94144 93116 92358 92506 93384 92813 93598 91153 91082 90777 92842 94009 94095 94516 92710 sum_num_inserts_total=8909433 sum_num_deletes_by_thread=91771 91848 93126 93311 93464 90996 92131 93154 93697 94139 93101 92802 91839 93253 93890 93669 93293 92838 91900 92068 92907 93964 93294 91553 91256 92017 93241 92896 92729 91255 91483 93037 93605 93293 92944 91852 92084 92566 94243 94377 93335 92527 90966 92941 93016 93914 92835 92137 92357 92541 94352 94482 92709 91950 92450 92903 93348 94159 93188 91705 92390 93416 93155 93777 92423 91969 91295 92625 93382 94049 92778 92421 91807 92543 94301 93657 93036 92665 91940 93158 93400 93365 94596 92585 92746 93374 93514 93750 90922 91005 91033 92741 94537 94111 93176 92518 sum_num_deletes_total=8912861 sum_num_searches_by_thread=18131126 18235838 18449161 18458117 18376766 18078540 18194630 18293839 18548194 18598771 18434993 18337404 18325303 18447397 18546533 18554654 18465361 18268470 18138751 18329886 18477944 18563072 18366962 18092593 18018767 18188972 18388742 18426613 18392270 18130185 18188609 18308610 18511686 18561289 18361760 18227411 18255348 18388023 18578392 18612757 18430479 18295292 18082972 18313912 18447515 18564610 18310099 18170947 18277632 18408878 18677635 18592984 18297202 18336191 18305058 18408216 18509025 18651623 18383199 18304961 18234231 18433284 18527308 18514465 18324745 18140101 18193685 18359151 18588045 18578290 18438148 18294188 18131194 18338303 18665333 18603855 18475290 18339872 18261024 18401472 18525700 18603051 18474627 18328585 18293302 18426106 18510239 18551162 18148903 18038001 18095311 18329256 18663030 18655930 18568452 18328398 sum_num_searches_total=1764404206 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=18314249 18420178 18635984 18644895 18563128 18260247 18379095 18479346 18735865 18786870 18620905 18523032 18509827 18633440 18733671 18742036 18652420 18453422 18321988 18514347 18663911 18750957 18552985 18275329 18201396 18372954 18574697 18611838 18577862 18313240 18371835 18494006 18699362 18747966 18547165 18411625 18439395 18573708 18766930 18801310 18617244 18479815 18265069 18499455 18633420 18752224 18495456 18354570 18462930 18594405 18866794 18780843 18482550 18520866 18490120 18593936 18696124 18839373 18568819 18489504 18418964 18620011 18713985 18701503 18509342 18323627 18376918 18545156 18775775 18766134 18624132 18478531 18314122 18523443 18853848 18791420 18661405 18525229 18444980 18587374 18712239 18790560 18662339 18513528 18478554 18612864 18696566 18738510 18330978 18220088 18277121 18514839 18851576 18844136 18756144 18513626 average_num_operations_total=18564859 stdev_num_operations_total=16957 sum_num_operations_total=1782226500 min_num_operations_total=18201396 max_num_operations_total=18866794 first_time_thread_terminate_by_thread=10000140 10000138 10000139 10000139 10000139 10000140 10000138 10000138 10000140 10000138 10000139 10000139 10000139 10000138 10000138 10000138 10000140 10000139 10000139 10000140 10000138 10000138 10000138 10000138 10000140 10000138 10000139 10000139 10000139 10000140 10000138 10000138 10000140 10000138 10000139 10000139 10000138 10000138 10000138 10000138 10000140 10000139 10000139 10000140 10000138 10000138 10000138 10000138 10000138 10000139 10000139 10000138 10000139 10000137 10000138 10000138 10000140 10000137 10000138 10000138 10000140 10000139 10000138 10000140 10000140 10000138 10000138 10000138 10000138 10000138 10000139 10000140 10000138 10000139 10000139 10000138 10000139 10000137 10000138 10000138 10000140 10000137 10000138 10000138 10000140 10000139 10000138 10000140 10000140 10000753 10000138 10000138 10000138 10000138 10000140 10000140 min_time_thread_terminate_total=10000137 max_time_thread_terminate_total=10000753 first_time_thread_start_by_thread=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 3 3 3 3 3 3 3 3 3 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=10011489063 threads_final_size=99940 final_keysum=10011489063 final_size=99940 validate_result=success Validation OK. Structural validation OK. total_find=1764404206 total_rq=0 total_inserts=8909433 total_deletes=8912861 total_updates=17822294 total_queries=1764404206 total_ops=1782226500 find_throughput=176440420 rq_throughput=0 update_throughput=1782229 query_throughput=176440420 total_throughput=178222650 total find : 1764404206 total rq : 0 total inserts : 8909433 total deletes : 8912861 total updates : 17822294 total queries : 1764404206 total ops : 1782226500 find throughput : 176440420 rq throughput : 0 update throughput : 1782229 query throughput : 176440420 total throughput : 178222650 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=10.0462 PAPI_L3_TCM=0.0773059 PAPI_TOT_CYC=1501.03 PAPI_TOT_INS=311.863 garbage=88202465836938 total_execution_walltime=11.566s REALTIME_MAIN_END_PERF_FORMAT=6981919.487805251 [time_cmd_output] time_elapsed_sec=11.69, faults_major=0, faults_minor=121002, mem_maxresident_kb=427852, user_cputime=1075.52, sys_cputime=6.03, percent_cpu=9244% timeout=false algorithm=ellen_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