__step=000162 __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 0.5 0.5 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000162.txt __path_data=data/data000162.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6980772.111187304 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=0.5 DEL_FRAC=0.5 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=0.5 0.5 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.02s prefilling_size=1000402 prefilling_elapsed_millis=1.001s finished prefilling to size 1000428 for expected size 1000000 keysum=1000000187294, performing 39408585 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=1000428 pref_millis=1102 prefill_elapsed_ms=1116 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=1871 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6980773.390271905 REALTIME_END_PERF_FORMAT=6980783.390409634 ############################################################################### ################################## 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 954 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.032s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 126 245 477 918 1722 3115 5388 9039 14522 22208 32083 43622 56395 68965 79698 87126 90578 89594 83924 74316 62920 50697 39229 28888 20048 13367 8476 5144 2950 1626 824 386 176 116 56 37 13 8 2 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 2 7 13 36 114 329 842 1737 3556 6836 12333 20544 30849 43825 58232 72270 83674 91562 95264 93532 85712 75143 62165 49570 37728 26729 18258 11808 7338 4274 2428 1262 596 236 176 75 61 18 14 4 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 252 490 954 1836 3444 6230 10776 18078 29044 44416 64166 87244 112790 137930 159396 174252 181156 179188 167848 148632 125840 101394 78458 57776 40096 26734 16952 10288 5900 3252 1648 772 352 232 112 74 26 16 4 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 2 7 13 36 114 329 842 1737 3556 6836 12333 20544 30849 43825 58232 72270 83674 91562 95264 93532 85712 75143 62165 49570 37728 26729 18258 11808 7338 4274 2428 1262 596 236 176 75 61 18 14 4 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.98438 1.97222 1.97347 1.96226 1.93791 1.90447 1.86485 1.83881 1.8033 1.76463 1.72233 1.67983 1.64641 1.61145 1.57781 1.5466 1.51981 1.49457 1.46836 1.44276 1.42333 1.40287 1.3869 1.3682 1.347 1.33337 1.31705 1.30345 1.28674 1.27559 1.25338 1.23422 1.22798 1.32955 1.24138 1.33036 1.17568 1.30769 1.125 1 tree_stats_height=47 tree_stats_numInternals=999151 tree_stats_numLeaves=999152 tree_stats_numNodes=1998303 tree_stats_numKeys=999152 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=25.3483 tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8064 15680 30528 58752 110208 199360 344832 578496 929408 1421312 2053312 2791808 3609280 4413760 5100672 5576064 5796992 5734016 5371136 4756224 4026880 3244608 2510656 1848832 1283072 855488 542464 329216 188800 104064 52736 24704 11264 7424 3584 2368 832 512 128 tree_stats_sizeInBytes=63945696 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=24544 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:175463 4:137923 8:14088 16:9 (2^01, 2^02]: 175463 (2^02, 2^03]: 137923 (2^03, 2^04]: 14088 (2^04, 2^05]: 9 sum_limbo_reclamation_event_size_total=1098638 count_limbo_reclamation_event_size_by_thread=6804 6709 6785 6783 6801 6837 6747 6790 6884 6897 6835 6795 6900 6804 6917 6858 6782 6894 6749 6808 6791 6840 6888 6752 6744 6767 6822 6814 6859 6733 6859 6904 6799 6864 6833 6774 6774 6894 6856 6955 6885 6705 6869 6831 6901 6873 6802 6716 count_limbo_reclamation_event_size_total=327483 sum_limbo_reclamation_event_count_total=1088304 first_thread_announced_epoch_by_thread=49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 49088 sum_num_inserts_by_thread=51118 51355 52135 51719 52028 50901 51450 51796 52595 52787 51666 51841 51240 51806 52033 52239 52250 52309 51415 51704 51832 52329 51952 50872 51188 51498 52253 51900 51735 51114 51417 52059 52516 52002 52014 51943 51784 51527 52427 52598 52497 51600 51294 51621 52270 52560 51993 51421 sum_num_inserts_total=2488603 sum_num_deletes_by_thread=50838 51354 52060 52395 52101 51337 51413 51703 52555 52908 52188 51378 52056 51890 52420 52668 51680 52125 51617 51662 52213 52380 51853 51230 51090 51236 52237 51969 51963 51119 51330 52364 52727 52248 52259 51822 51725 52033 52395 52939 52363 51736 51222 51822 52506 52584 52003 51442 sum_num_deletes_total=2493158 sum_num_searches_by_thread=10153198 10234490 10336911 10312879 10289591 10145309 10190389 10258191 10399637 10416482 10298576 10234614 10217160 10269942 10344262 10367964 10345402 10268489 10186256 10258625 10337042 10381598 10258024 10163812 10130299 10226430 10337302 10325938 10292044 10162950 10202812 10266440 10418102 10408164 10310988 10239899 10237997 10274218 10385876 10395975 10338239 10260758 10172101 10233746 10322074 10388658 10275178 10176296 sum_num_searches_total=493451327 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=10255154 10337199 10441106 10416993 10393720 10247547 10293252 10361690 10504787 10522177 10402430 10337833 10320456 10373638 10448715 10472871 10449332 10372923 10289288 10361991 10441087 10486307 10361829 10265914 10232577 10329164 10441792 10429807 10395742 10265183 10305559 10370863 10523345 10512414 10415261 10343664 10341506 10377778 10490698 10501512 10443099 10364094 10274617 10337189 10426850 10493802 10379174 10279159 average_num_operations_total=10384022 stdev_num_operations_total=11758 sum_num_operations_total=498433088 min_num_operations_total=10232577 max_num_operations_total=10523345 first_time_thread_terminate_by_thread=10000137 10000137 10000137 10000136 10000136 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000136 10000137 10000136 10000137 10000137 10000136 10000137 10000137 10000136 10000136 10000137 10000137 10000137 10000137 10000136 10000136 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000136 10000137 10000136 10000137 10000137 10000136 10000137 10000137 10000136 10000136 10000137 min_time_thread_terminate_total=10000136 max_time_thread_terminate_total=10000137 first_time_thread_start_by_thread=4 5 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 5 4 4 4 4 5 4 5 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 5 4 4 4 4 5 min_time_thread_start_total=4 max_time_thread_start_total=5 sum_duration_all_ops_total= threads_final_keysum=999031427559 threads_final_size=999152 final_keysum=999031427559 final_size=999152 validate_result=success Validation OK. Structural validation OK. total_find=493451327 total_rq=0 total_inserts=2488603 total_deletes=2493158 total_updates=4981761 total_queries=493451327 total_ops=498433088 find_throughput=49345132 rq_throughput=0 update_throughput=498176 query_throughput=49345132 total_throughput=49843308 total find : 493451327 total rq : 0 total inserts : 2488603 total deletes : 2493158 total updates : 4981761 total queries : 493451327 total ops : 498433088 find throughput : 49345132 rq throughput : 0 update throughput : 498176 query throughput : 49345132 total throughput : 49843308 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=11.8286 PAPI_L3_TCM=1.63768 PAPI_TOT_CYC=2684.24 PAPI_TOT_INS=326.62 garbage=246649065377500 total_execution_walltime=11.422s REALTIME_MAIN_END_PERF_FORMAT=6980783.534510639 [time_cmd_output] time_elapsed_sec=11.54, faults_major=0, faults_minor=90860, mem_maxresident_kb=365684, user_cputime=561.78, sys_cputime=3.68, percent_cpu=4896% 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