__step=000833 __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 50.0 50.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000833.txt __path_data=data/data000833.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6989018.884438507 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=2000000 PREFILL_THREADS=48 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=48 WORK_THREADS=48 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 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.005s prefilling_size=999280 prefilling_elapsed_millis=1.001s finished prefilling to size 999219 for expected size 1000000 keysum=999345914681, performing 39710737 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=999219 pref_millis=1102 prefill_elapsed_ms=1127 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=1894 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6989020.181928742 REALTIME_END_PERF_FORMAT=6989030.182082949 ############################################################################### ################################## 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 946 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.039s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 251 473 870 1569 2778 4856 8028 12547 18218 25206 33200 42264 51805 60809 68305 73530 76868 77840 76107 71025 63995 55271 46066 36463 27720 20259 14454 9930 6567 4180 2740 1821 1224 787 545 330 183 80 25 13 3 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 3 29 76 171 360 700 1684 3509 6876 11230 17212 24136 32723 42801 53313 63080 70192 75896 79573 81189 78055 72719 64476 55669 45206 35181 26064 18978 13293 8954 5620 3659 2418 1661 1029 760 477 286 135 37 23 6 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 254 502 946 1740 3138 5556 9712 16056 25094 36436 50412 66400 84528 103610 121618 136610 147060 153736 155680 152214 142050 127990 110542 92132 72926 55440 40518 28908 19860 13134 8360 5480 3642 2448 1574 1090 660 366 160 50 26 6 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 1 3 29 76 171 360 700 1684 3509 6876 11230 17212 24136 32723 42801 53313 63080 70192 75896 79573 81189 78055 72719 64476 55669 45206 35181 26064 18978 13293 8954 5620 3659 2418 1661 1029 760 477 286 135 37 23 6 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.98819 1.94223 1.91966 1.90172 1.88528 1.87401 1.82661 1.78145 1.72599 1.69179 1.65857 1.63651 1.61287 1.5869 1.56164 1.53825 1.5227 1.50632 1.48887 1.46661 1.45051 1.43184 1.41673 1.39577 1.38011 1.36542 1.35673 1.3435 1.33066 1.31826 1.32775 1.3323 1.33608 1.32149 1.34625 1.30275 1.27727 1.21858 1.15625 1.26 1.11538 1 tree_stats_height=50 tree_stats_numInternals=999459 tree_stats_numLeaves=999460 tree_stats_numNodes=1998919 tree_stats_numKeys=999460 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=26.7588 tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8128 16064 30272 55680 100416 177792 310784 513792 803008 1165952 1613184 2124800 2704896 3315520 3891776 4371520 4705920 4919552 4981760 4870848 4545600 4095680 3537344 2948224 2333632 1774080 1296576 925056 635520 420288 267520 175360 116544 78336 50368 34880 21120 11712 5120 1600 832 192 tree_stats_sizeInBytes=63965408 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=20368 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:56 4:6 8:15 16:23 32:45 64:140 128:461940 256:17348 512:136 1024:18 2048:47 (2^01, 2^02]: 56 (2^02, 2^03]: 6 (2^03, 2^04]: 15 (2^04, 2^05]: 23 (2^05, 2^06]: 45 (2^06, 2^07]: 140 (2^07, 2^08]: 461940 (2^08, 2^09]: 17348 (2^09, 2^10]: 136 (2^10, 2^11]: 18 (2^11, 2^12]: 47 sum_limbo_reclamation_event_size_total=105005764 count_limbo_reclamation_event_size_by_thread=9996 9994 9993 9997 9994 9995 9994 9993 9996 9995 9996 9995 9995 9997 9996 9995 9994 9995 9994 9995 9995 9998 9995 9996 9995 9996 9994 9995 9995 9996 9996 9996 9993 9994 9995 9993 9998 9996 9995 9994 9994 9997 9998 9996 9997 9995 9997 9996 count_limbo_reclamation_event_size_total=479774 sum_limbo_reclamation_event_count_total=886578 first_thread_announced_epoch_by_thread=40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 40736 sum_num_inserts_by_thread=4006638 4023513 4060844 4065687 4049146 4003622 4016427 4035420 4076316 4081293 4051285 4028225 4021800 4033969 4054983 4071522 4049041 4030237 4002022 4031431 4052268 4065604 4023950 4005511 4004203 4022414 4062837 4061890 4038040 4010789 4014941 4043361 4085836 4088988 4036861 4027667 4018634 4041617 4072007 4075733 4051916 4030332 3996174 4028900 4052320 4073981 4028117 4008622 sum_num_inserts_total=193916934 sum_num_deletes_by_thread=4012344 4026732 4061853 4063969 4052031 3999764 4016488 4038056 4077441 4080722 4052499 4029689 4024541 4033593 4054023 4067979 4050798 4029157 4002838 4028937 4057936 4059271 4027685 4004093 4003204 4021991 4063910 4063230 4035825 4006001 4015970 4042218 4088525 4090788 4041610 4022914 4021280 4039963 4068201 4072007 4053847 4028787 3997506 4025488 4052629 4072251 4030729 4011839 sum_num_deletes_total=193923152 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=8018982 8050245 8122697 8129656 8101177 8003386 8032915 8073476 8153757 8162015 8103784 8057914 8046341 8067562 8109006 8139501 8099839 8059394 8004860 8060368 8110204 8124875 8051635 8009604 8007407 8044405 8126747 8125120 8073865 8016790 8030911 8085579 8174361 8179776 8078471 8050581 8039914 8081580 8140208 8147740 8105763 8059119 7993680 8054388 8104949 8146232 8058846 8020461 average_num_operations_total=8080001 stdev_num_operations_total=7274 sum_num_operations_total=387840086 min_num_operations_total=7993680 max_num_operations_total=8179776 first_time_thread_terminate_by_thread=10000154 10000154 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000154 10000153 10000153 10000153 10000153 10000153 10000154 10000153 10000153 10000153 10000158 10000153 10000154 10000153 10000153 10000154 10000161 10000153 10000153 10000153 10000153 10000153 10000153 10000153 10000154 10000153 10000153 10000153 10000153 10000153 10000161 10000153 10000153 10000153 10000153 min_time_thread_terminate_total=10000153 max_time_thread_terminate_total=10000161 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 min_time_thread_start_total=3 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=999711479474 threads_final_size=999460 final_keysum=999711479474 final_size=999460 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=193916934 total_deletes=193923152 total_updates=387840086 total_queries=0 total_ops=387840086 find_throughput=0 rq_throughput=0 update_throughput=38784008 query_throughput=0 total_throughput=38784008 total find : 0 total rq : 0 total inserts : 193916934 total deletes : 193923152 total updates : 387840086 total queries : 0 total ops : 387840086 find throughput : 0 rq throughput : 0 update throughput : 38784008 query throughput : 0 total throughput : 38784008 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=16.8214 PAPI_L3_TCM=2.31462 PAPI_TOT_CYC=3447.75 PAPI_TOT_INS=1006.47 garbage=0 total_execution_walltime=11.433s REALTIME_MAIN_END_PERF_FORMAT=6989030.318186004 [time_cmd_output] time_elapsed_sec=11.56, faults_major=0, faults_minor=92631, mem_maxresident_kb=369924, user_cputime=561.73, sys_cputime=1.32, percent_cpu=4868% 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