__step=000050 __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 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000050.txt __path_data=data/data000050.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6979358.590488616 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=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 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=7166456 prefilling_elapsed_millis=1.001s prefilling_size=7435307 prefilling_elapsed_millis=1.101s prefilling_size=7674515 prefilling_elapsed_millis=1.201s prefilling_size=7888735 prefilling_elapsed_millis=1.301s prefilling_size=8081196 prefilling_elapsed_millis=1.402s prefilling_size=8254562 prefilling_elapsed_millis=1.502s prefilling_size=8410759 prefilling_elapsed_millis=1.602s prefilling_size=8552682 prefilling_elapsed_millis=1.702s prefilling_size=8680307 prefilling_elapsed_millis=1.802s prefilling_size=8796675 prefilling_elapsed_millis=1.903s prefilling_size=8900990 prefilling_elapsed_millis=2.003s prefilling_size=8995277 prefilling_elapsed_millis=2.103s prefilling_size=9080482 prefilling_elapsed_millis=2.203s prefilling_size=9157391 prefilling_elapsed_millis=2.303s prefilling_size=9229021 prefilling_elapsed_millis=2.404s prefilling_size=9294466 prefilling_elapsed_millis=2.504s prefilling_size=9352905 prefilling_elapsed_millis=2.604s prefilling_size=9406887 prefilling_elapsed_millis=2.704s prefilling_size=9457931 prefilling_elapsed_millis=2.804s prefilling_size=9503457 prefilling_elapsed_millis=2.905s prefilling_size=9545744 prefilling_elapsed_millis=3.005s prefilling_size=9581171 prefilling_elapsed_millis=3.105s prefilling_size=9614521 prefilling_elapsed_millis=3.205s prefilling_size=9646437 prefilling_elapsed_millis=3.305s prefilling_size=9676836 prefilling_elapsed_millis=3.406s prefilling_size=9702206 prefilling_elapsed_millis=3.506s prefilling_size=9728740 prefilling_elapsed_millis=3.606s prefilling_size=9748865 prefilling_elapsed_millis=3.706s prefilling_size=9769384 prefilling_elapsed_millis=3.806s prefilling_size=9788318 prefilling_elapsed_millis=3.907s prefilling_size=9805369 prefilling_elapsed_millis=4.007s finished prefilling to size 9805392 for expected size 10000000 keysum=98066997336721, performing 83002606 updates; total_prefilling_elapsed_ms=4108 ms) pref_size=9805392 pref_millis=4108 prefill_elapsed_ms=4125 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=3814 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6979362.894282525 REALTIME_END_PERF_FORMAT=6979372.894415048 ############################################################################### ################################## 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 1016 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.106s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 254 508 1003 1973 3804 7185 13173 23389 40004 65715 103171 153639 217985 295660 383586 474958 564988 645494 709608 751633 764944 749605 708143 643940 565846 478396 391484 308942 235118 172766 122605 83516 55162 35159 21607 12846 7349 4126 2285 1199 594 280 134 67 24 3 1 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 0 0 13 33 142 423 1197 2957 6774 14293 28259 52703 89293 140310 207734 292214 384928 484482 581380 667583 738322 780283 791067 772346 722034 653296 565308 474026 382766 297470 222927 161694 111870 75165 48711 30368 18343 10572 5967 3371 1804 908 426 201 110 45 5 1 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 254 508 1016 2006 3946 7608 14370 26346 46778 80008 131430 206342 307278 435970 591320 767172 949916 1129976 1290988 1419216 1503266 1529888 1499210 1416286 1287880 1131692 956792 782968 617884 470236 345532 245210 167032 110324 70318 43214 25692 14698 8252 4570 2398 1188 560 268 134 48 6 2 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 1 0 0 13 33 142 423 1197 2957 6774 14293 28259 52703 89293 140310 207734 292214 384928 484482 581380 667583 738322 780283 791067 772346 722034 653296 565308 474026 382766 297470 222927 161694 111870 75165 48711 30368 18343 10572 5967 3371 1804 908 426 201 110 45 5 1 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 2 2 1.9872 1.98355 1.96401 1.9444 1.9167 1.88776 1.85519 1.82136 1.78499 1.74458 1.70941 1.67817 1.64869 1.6191 1.59478 1.57125 1.54966 1.52961 1.50885 1.48997 1.47234 1.45467 1.43936 1.42273 1.40916 1.39458 1.38052 1.3674 1.35483 1.34059 1.33025 1.31869 1.30728 1.29726 1.28604 1.28072 1.2769 1.26236 1.24771 1.23569 1.23929 1.25 1.1791 1.0625 1.16667 1.5 1 tree_stats_height=57 tree_stats_numInternals=9824126 tree_stats_numLeaves=9824127 tree_stats_numNodes=19648253 tree_stats_numKeys=9824127 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=30.1558 tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8128 16256 32512 64192 126272 243456 459840 843072 1496896 2560256 4205760 6602944 9832896 13951040 18922240 24549504 30397312 36159232 41311616 45414912 48104512 48956416 47974720 45321152 41212160 36214144 30617344 25054976 19772288 15047552 11057024 7846720 5345024 3530368 2250176 1382848 822144 470336 264064 146240 76736 38016 17920 8576 4288 1536 192 64 64 tree_stats_sizeInBytes=628744096 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=13489 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:169638 4:132248 8:12930 16:14 (2^01, 2^02]: 169638 (2^02, 2^03]: 132248 (2^03, 2^04]: 12930 (2^04, 2^05]: 14 sum_limbo_reclamation_event_size_total=1050680 count_limbo_reclamation_event_size_by_thread=6565 6581 6543 6605 6588 6497 6497 6589 6617 6699 6464 6572 6598 6528 6544 6618 6514 6487 6481 6519 6647 6615 6562 6513 6487 6530 6682 6504 6464 6491 6516 6538 6692 6543 6574 6500 6571 6631 6657 6565 6612 6600 6538 6459 6493 6628 6632 6480 count_limbo_reclamation_event_size_total=314830 sum_limbo_reclamation_event_count_total=464400 first_thread_announced_epoch_by_thread=26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 sum_num_inserts_by_thread=22005 22350 22497 22172 22551 21975 22237 22299 22483 22475 22432 22151 21745 22242 22445 22400 22388 22154 22105 22102 22660 22484 22042 22354 21850 22044 22498 22416 22263 22041 22003 22457 22253 22591 22226 22017 22006 22346 22317 22526 22455 22334 22209 22047 22477 22376 22328 22170 sum_num_inserts_total=1068998 sum_num_deletes_by_thread=22213 22256 22483 22423 22234 21986 22099 22119 22591 22495 22285 22070 22168 22499 22426 22570 22510 22113 21888 22080 22583 22390 22264 21968 22226 22280 22315 22353 22299 22075 21990 22403 22455 22420 22229 22057 22072 22396 22389 22366 22383 22299 22114 21914 22389 22266 22050 22166 sum_num_deletes_total=1068619 sum_num_searches_by_thread=4363994 4407160 4440564 4423523 4410734 4381007 4399836 4401601 4446565 4452949 4423941 4385876 4386769 4424708 4454188 4438814 4439422 4397300 4382160 4389830 4416772 4436097 4394593 4381562 4382114 4408782 4426648 4439196 4428363 4364382 4386165 4421024 4451181 4456163 4410956 4402961 4392389 4411375 4443503 4442785 4423473 4394847 4380751 4405062 4432713 4436255 4393453 4382637 sum_num_searches_total=211797143 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=4408212 4451766 4485544 4468118 4455519 4424968 4444172 4446019 4491639 4497919 4468658 4430097 4430682 4469449 4499059 4483784 4484320 4441567 4426153 4434012 4462015 4480971 4438899 4425884 4426190 4453106 4471461 4483965 4472925 4408498 4430158 4465884 4495889 4501174 4455411 4447035 4436467 4456117 4488209 4487677 4468311 4439480 4425074 4449023 4477579 4480897 4437831 4426973 average_num_operations_total=4456974 stdev_num_operations_total=3750 sum_num_operations_total=213934760 min_num_operations_total=4408212 max_num_operations_total=4501174 first_time_thread_terminate_by_thread=10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 min_time_thread_terminate_total=10000133 max_time_thread_terminate_total=10000133 first_time_thread_start_by_thread=3 3 3 4 3 3 3 3 3 3 3 3 3 3 4 3 4 3 4 3 3 4 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 4 3 4 3 4 3 3 4 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=98251258595482 threads_final_size=9824127 final_keysum=98251258595482 final_size=9824127 validate_result=success Validation OK. Structural validation OK. total_find=211797143 total_rq=0 total_inserts=1068998 total_deletes=1068619 total_updates=2137617 total_queries=211797143 total_ops=213934760 find_throughput=21179714 rq_throughput=0 update_throughput=213761 query_throughput=21179714 total_throughput=21393476 total find : 211797143 total rq : 0 total inserts : 1068998 total deletes : 1068619 total updates : 2137617 total queries : 211797143 total ops : 213934760 find throughput : 21179714 rq throughput : 0 update throughput : 213761 query throughput : 21179714 total throughput : 21393476 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=24.9669 PAPI_L3_TCM=8.96627 PAPI_TOT_CYC=6256.36 PAPI_TOT_INS=361.003 garbage=1041397553411136 total_execution_walltime=14.496s REALTIME_MAIN_END_PERF_FORMAT=6979373.108556872 [time_cmd_output] time_elapsed_sec=14.71, faults_major=0, faults_minor=232889, mem_maxresident_kb=933712, user_cputime=710.34, sys_cputime=3.04, percent_cpu=4847% 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