__step=000002 __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" ./brown_ext_abtree_lf.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000002.txt __path_data=data/data000002.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6978713.54027772 binary=./brown_ext_abtree_lf.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_ext_abtree_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=20000000 PREFILL_THREADS=48 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=48 WORK_THREADS=48 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 size_node=208 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.006s prefilling_size=8739710 prefilling_elapsed_millis=1.001s prefilling_size=8960065 prefilling_elapsed_millis=1.101s prefilling_size=9143028 prefilling_elapsed_millis=1.201s prefilling_size=9293326 prefilling_elapsed_millis=1.301s prefilling_size=9419391 prefilling_elapsed_millis=1.402s prefilling_size=9523654 prefilling_elapsed_millis=1.502s prefilling_size=9606496 prefilling_elapsed_millis=1.602s prefilling_size=9675605 prefilling_elapsed_millis=1.702s prefilling_size=9729925 prefilling_elapsed_millis=1.802s prefilling_size=9772947 prefilling_elapsed_millis=1.903s prefilling_size=9812406 prefilling_elapsed_millis=2.003s finished prefilling to size 9812419 for expected size 10000000 keysum=98108522794987, performing 79510223 updates; total_prefilling_elapsed_ms=2104 ms) pref_size=9812419 pref_millis=2104 prefill_elapsed_ms=2130 N9abtree_ns4NodeILi11ExEE_object_size=208 N9abtree_ns4NodeILi11ExEE_allocated_count=0 N9abtree_ns4NodeILi11ExEE_allocated_size=0MB N9abtree_ns4NodeILi11ExEE_get_from_pool=0 N9abtree_ns4NodeILi11ExEE_deallocated=0 N9abtree_ns4NodeILi11ExEE_limbo_count=0 N9abtree_ns4NodeILi11ExEE_limbo_details=0 0 0 global_epoch_counter=3858 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6978715.365891111 REALTIME_END_PERF_FORMAT=6978725.366042087 ############################################################################### ################################## 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 3460 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.063s tree_stats_numInternalsAtDepth=1 1 8 64 474 3460 26051 203704 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1607442 tree_stats_numNodesAtDepth=1 1 8 64 474 3460 26051 203704 1607442 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9860862 tree_stats_avgDegreeAtDepth=1 8 8 7.40625 7.29958 7.52919 7.81943 7.89107 6.13451 tree_stats_height=9 tree_stats_numInternals=233763 tree_stats_numLeaves=1607442 tree_stats_numNodes=1841205 tree_stats_numKeys=9860862 tree_stats_avgDegreeInternal=7.87637 tree_stats_avgDegreeLeaves=6.13451 tree_stats_avgDegree=6.35566 tree_stats_avgKeyDepth=8 tree_stats_bytesAtDepth=208 208 1664 13312 98592 719680 5418608 42370432 334347936 tree_stats_sizeInBytes=382970640 N9abtree_ns4NodeILi11ExEE_object_size=208 N9abtree_ns4NodeILi11ExEE_allocated_count=0 N9abtree_ns4NodeILi11ExEE_allocated_size=0MB N9abtree_ns4NodeILi11ExEE_get_from_pool=0 N9abtree_ns4NodeILi11ExEE_deallocated=0 N9abtree_ns4NodeILi11ExEE_limbo_count=0 N9abtree_ns4NodeILi11ExEE_limbo_details=0 0 0 global_epoch_counter=29682 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:108576 2:219656 4:101423 8:3241 16:4 [2^00, 2^01]: 108576 (2^01, 2^02]: 219656 (2^02, 2^03]: 101423 (2^03, 2^04]: 3241 (2^04, 2^05]: 4 sum_limbo_reclamation_event_size_total=1144441 count_limbo_reclamation_event_size_by_thread=9030 9061 9031 8961 9082 9023 8982 9047 9072 9013 9046 9006 9012 9028 9000 9065 8990 9031 9028 9002 8983 9039 8988 8978 9020 8948 9017 8982 9040 8960 8950 9019 9059 9100 8982 9035 8958 9015 9081 9045 9034 9034 8990 9040 9068 9030 9002 8993 count_limbo_reclamation_event_size_total=432900 sum_limbo_reclamation_event_count_total=1239552 first_thread_announced_epoch_by_thread=59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 59364 sum_num_inserts_by_thread=59622 59404 60034 60350 60111 59215 59345 59965 60065 60304 59397 59081 59231 59369 59880 59604 59249 59537 58898 59233 59906 59992 59396 58906 59565 59208 59868 59678 59492 58905 59422 60122 60128 60446 59996 59473 58864 59597 60371 60246 59861 58930 58921 59593 60014 60076 59223 58773 sum_num_inserts_total=2860866 sum_num_deletes_by_thread=59095 59703 59834 59802 59871 59454 59022 59407 59829 60334 59778 59418 59231 59180 60392 59889 59722 59436 59021 59060 59689 60210 59539 58346 58560 59824 59384 59844 59833 58956 59380 59798 60333 59793 59635 59055 59463 59949 59720 60225 59530 59254 58854 58750 59820 59725 59523 58859 sum_num_deletes_total=2857329 sum_num_searches_by_thread=11730602 11790099 11863828 11856920 11838754 11736497 11762797 11814445 11930381 11912658 11774692 11715776 11744176 11786035 11903101 11888720 11835981 11740468 11694568 11732933 11813337 11867376 11768165 11678373 11744568 11733425 11849700 11844968 11828413 11699819 11748187 11818178 11936077 11931097 11811558 11714655 11733654 11825243 11889399 11861885 11844432 11742009 11689531 11734749 11805635 11838452 11759022 11661178 sum_num_searches_total=566226516 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=11849319 11909206 11983696 11977072 11958736 11855166 11881164 11933817 12050275 12033296 11893867 11834275 11862638 11904584 12023373 12008213 11954952 11859441 11812487 11851226 11932932 11987578 11887100 11795625 11862693 11852457 11968952 11964490 11947738 11817680 11866989 11938098 12056538 12051336 11931189 11833183 11851981 11944789 12009490 11982356 11963823 11860193 11807306 11853092 11925469 11958253 11877768 11778810 average_num_operations_total=11915514 stdev_num_operations_total=10730 sum_num_operations_total=571944711 min_num_operations_total=11778810 max_num_operations_total=12056538 first_time_thread_terminate_by_thread=10000150 10000150 10000150 10000149 10000150 10000150 10000149 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000149 10000150 10000150 10000149 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 10000150 min_time_thread_terminate_total=10000149 max_time_thread_terminate_total=10000150 first_time_thread_start_by_thread=3 3 3 3 2 2 3 2 3 3 3 3 3 3 3 3 2 2 3 3 2 2 3 3 3 3 3 3 2 2 3 2 3 3 3 3 3 3 3 3 2 2 3 3 2 2 3 3 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=98578979253467 threads_final_size=9860862 final_keysum=98578979253467 final_size=9860862 validate_result=success Validation OK. Structural validation OK. total_find=566226516 total_rq=0 total_inserts=2860866 total_deletes=2857329 total_updates=5718195 total_queries=566226516 total_ops=571944711 find_throughput=56622651 rq_throughput=0 update_throughput=571819 query_throughput=56622651 total_throughput=57194471 total find : 566226516 total rq : 0 total inserts : 2860866 total deletes : 2857329 total updates : 5718195 total queries : 566226516 total ops : 571944711 find throughput : 56622651 rq throughput : 0 update throughput : 571819 query throughput : 56622651 total throughput : 57194471 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=17.1865 PAPI_L3_TCM=5.24962 PAPI_TOT_CYC=2335.04 PAPI_TOT_INS=380.707 garbage=2784783752198882 total_execution_walltime=12.453s REALTIME_MAIN_END_PERF_FORMAT=6978725.522135170 [time_cmd_output] time_elapsed_sec=12.63, faults_major=0, faults_minor=176504, mem_maxresident_kb=707560, user_cputime=612.82, sys_cputime=2.53, percent_cpu=4869% timeout=false algorithm=brown_ext_abtree_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