__step=000372 __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 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000372.txt __path_data=data/data000372.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6983291.852156750 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=5 DEL_FRAC=5 RQ=0 RQSIZE=0 MAXKEY=20000000 PREFILL_THREADS=96 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=96 WORK_THREADS=96 RQ_THREADS=0 distribution=0 INS_DEL_FRAC=5.0 5.0 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.014s prefilling_size=7394805 prefilling_elapsed_millis=1.001s prefilling_size=7680280 prefilling_elapsed_millis=1.101s prefilling_size=7925666 prefilling_elapsed_millis=1.201s prefilling_size=8143498 prefilling_elapsed_millis=1.302s prefilling_size=8337663 prefilling_elapsed_millis=1.402s prefilling_size=8509141 prefilling_elapsed_millis=1.502s prefilling_size=8665433 prefilling_elapsed_millis=1.602s prefilling_size=8804817 prefilling_elapsed_millis=1.702s prefilling_size=8928814 prefilling_elapsed_millis=1.803s prefilling_size=9039841 prefilling_elapsed_millis=1.903s prefilling_size=9139387 prefilling_elapsed_millis=2.003s prefilling_size=9226268 prefilling_elapsed_millis=2.103s prefilling_size=9305259 prefilling_elapsed_millis=2.203s prefilling_size=9375906 prefilling_elapsed_millis=2.304s prefilling_size=9437453 prefilling_elapsed_millis=2.404s prefilling_size=9492347 prefilling_elapsed_millis=2.504s prefilling_size=9541389 prefilling_elapsed_millis=2.604s prefilling_size=9585339 prefilling_elapsed_millis=2.705s prefilling_size=9627130 prefilling_elapsed_millis=2.805s prefilling_size=9663883 prefilling_elapsed_millis=2.905s prefilling_size=9696081 prefilling_elapsed_millis=3.005s prefilling_size=9726853 prefilling_elapsed_millis=3.105s prefilling_size=9754462 prefilling_elapsed_millis=3.206s prefilling_size=9777878 prefilling_elapsed_millis=3.306s prefilling_size=9798088 prefilling_elapsed_millis=3.406s prefilling_size=9818587 prefilling_elapsed_millis=3.506s finished prefilling to size 9818649 for expected size 10000000 keysum=98175387801851, performing 83516306 updates; total_prefilling_elapsed_ms=3608 ms) pref_size=9818649 pref_millis=3608 prefill_elapsed_ms=3631 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=1257 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=6983295.684162521 REALTIME_END_PERF_FORMAT=6983305.684247745 ############################################################################### ################################## 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 986 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.259s tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 64 128 252 493 949 1760 3170 5573 9328 15091 23352 35317 52576 77238 110701 154595 210467 278719 357490 443326 529840 612018 680051 728466 752905 750124 720404 667765 596304 512596 423244 335857 257473 191590 138512 97616 66780 44842 29502 19323 12354 8052 5095 3327 2104 1311 767 418 204 102 46 9 1 1 0 tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 0 0 4 11 37 138 350 767 1818 3565 6830 11387 18058 27914 43775 66807 98723 142215 199948 271654 356812 447662 543985 631636 704027 755686 779844 773043 739226 680012 601948 510631 414241 323356 244668 179408 128452 88718 60182 39681 26292 16656 11009 6863 4550 2897 1855 1116 632 306 158 83 17 1 2 tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 128 256 504 986 1898 3520 6340 11146 18656 30182 46704 70634 105152 154476 221402 309190 420934 557438 714980 886652 1059680 1224036 1360102 1456932 1505810 1500248 1440808 1335530 1192608 1025192 846488 671714 514946 383180 277024 195232 133560 89684 59004 38646 24708 16104 10190 6654 4208 2622 1534 836 408 204 92 18 2 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 4 11 37 138 350 767 1818 3565 6830 11387 18058 27914 43775 66807 98723 142215 199948 271654 356812 447662 543985 631636 704027 755686 779844 773043 739226 680012 601948 510631 414241 323356 244668 179408 128452 88718 60182 39681 26292 16656 11009 6863 4550 2897 1855 1116 632 306 158 83 17 1 2 tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 2 2 1.98438 1.97817 1.96247 1.92729 1.90057 1.87902 1.83689 1.80891 1.77371 1.75619 1.74434 1.73454 1.71662 1.69825 1.6807 1.66214 1.64131 1.62005 1.59757 1.57755 1.55558 1.5356 1.51677 1.49815 1.48019 1.46347 1.44649 1.42981 1.41284 1.39677 1.38331 1.37206 1.36148 1.35237 1.34205 1.33574 1.32895 1.32749 1.31967 1.32589 1.31638 1.3265 1.3162 1.31155 1.29252 1.27249 1.24402 1.25 1.22549 1.09783 1.05556 1.5 1 tree_stats_height=63 tree_stats_numInternals=9969657 tree_stats_numLeaves=9969658 tree_stats_numNodes=19939315 tree_stats_numKeys=9969656 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=34.3561 tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 5120 10240 20160 39440 75920 140800 253600 445840 746240 1207280 1868160 2825360 4206080 6179040 8856080 12367600 16837360 22297520 28599200 35466080 42387200 48961440 54404080 58277280 60232400 60009920 57632320 53421200 47704320 41007680 33859520 26868560 20597840 15327200 11080960 7809280 5342400 3587360 2360160 1545840 988320 644160 407600 266160 168320 104880 61360 33440 16320 8160 3680 720 80 80 tree_stats_sizeInBytes=797572600 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=5864 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:0 2:0 4:0 8:0 16:3773 32:560531 64:319648 128:205 256:3 (2^04, 2^05]: 3773 (2^05, 2^06]: 560531 (2^06, 2^07]: 319648 (2^07, 2^08]: 205 (2^08, 2^09]: 3 sum_limbo_reclamation_event_size_total=51405398 count_limbo_reclamation_event_size_by_thread=9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 9210 count_limbo_reclamation_event_size_total=884160 sum_limbo_reclamation_event_count_total=884544 first_thread_announced_epoch_by_thread=11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 11728 sum_num_inserts_by_thread=212741 213646 214860 216816 213937 212502 213234 215038 216447 216469 214344 212681 214278 215127 216290 216362 215359 212504 212581 214122 214772 216316 213319 212095 211824 213847 216455 214811 214397 212487 213114 214603 216169 215927 214463 212820 214180 214899 217248 216503 214683 212546 210942 213913 215417 216808 213448 210374 213521 214918 215898 215639 214055 213184 212567 214107 216559 216680 214066 212287 214523 215612 217583 216193 214996 211976 212065 213078 216064 215633 214960 213176 213007 214961 216832 217404 214889 213253 213497 215058 216394 217304 215056 212855 214450 215267 216724 216679 215030 212542 212068 212907 216571 216064 215054 213532 sum_num_inserts_total=20594456 sum_num_deletes_by_thread=213127 213719 215331 215394 215314 211213 213191 214370 216440 215858 213909 213720 213664 214230 215881 216236 214842 213343 212886 214443 215185 215939 213055 211648 212376 214535 215619 215698 214320 212653 213505 214101 216465 216385 214552 212474 213545 214360 216449 217675 214713 213968 212654 213741 214845 216253 213656 211436 214222 215163 215844 215858 214279 212661 213106 214357 216349 217238 214804 213421 213800 215283 217567 217033 214500 212083 211960 212974 216359 215383 214704 213967 212854 214330 215696 215787 214530 213318 213041 214428 217301 217106 215447 212910 214417 215223 216995 217299 214952 213363 212381 212890 216370 217129 214257 212694 sum_num_deletes_total=20596549 sum_num_searches_by_thread=3827711 3847587 3884720 3883338 3861925 3814136 3843953 3859944 3900462 3899869 3847498 3834766 3851780 3877897 3898786 3892914 3873115 3836245 3829213 3844268 3874324 3890638 3832899 3801769 3827209 3850958 3883205 3878194 3862244 3817505 3837575 3857139 3891209 3900307 3853472 3823431 3846159 3865446 3903257 3894635 3861243 3836948 3815696 3846766 3864791 3894075 3846376 3799279 3849578 3861831 3895011 3885500 3855000 3835010 3845853 3855987 3894812 3899780 3866600 3838111 3849307 3868260 3906416 3893436 3865794 3820018 3815762 3837946 3891217 3882804 3861804 3834099 3836402 3864097 3885555 3891721 3852329 3830305 3853719 3866809 3902488 3912236 3873898 3838153 3862069 3873700 3905063 3902341 3859529 3831819 3811561 3830631 3896513 3894987 3868035 3842890 sum_num_searches_total=370663662 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=4253579 4274952 4314911 4315548 4291176 4237851 4270378 4289352 4333349 4332196 4275751 4261167 4279722 4307254 4330957 4325512 4303316 4262092 4254680 4272833 4304281 4322893 4259273 4225512 4251409 4279340 4315279 4308703 4290961 4242645 4264194 4285843 4323843 4332619 4282487 4248725 4273884 4294705 4336954 4328813 4290639 4263462 4239292 4274420 4295053 4327136 4273480 4221089 4277321 4291912 4326753 4316997 4283334 4260855 4271526 4284451 4327720 4333698 4295470 4263819 4277630 4299155 4341566 4326662 4295290 4244077 4239787 4263998 4323640 4313820 4291468 4261242 4262263 4293388 4318083 4324912 4281748 4256876 4280257 4296295 4336183 4346646 4304401 4263918 4290936 4304190 4338782 4336319 4289511 4257724 4236010 4256428 4329454 4328180 4297346 4269116 average_num_operations_total=4290152 stdev_num_operations_total=3163 sum_num_operations_total=411854667 min_num_operations_total=4221089 max_num_operations_total=4346646 first_time_thread_terminate_by_thread=10000088 10000086 10000085 10000086 10000086 10000086 10000085 10000086 10000086 10000086 10000086 10000088 10000089 10000086 10000085 10000088 10000085 10000087 10000085 10000088 10000087 10000086 10000085 10000088 10000086 10000086 10000086 10000086 10000087 10000088 10000088 10000086 10000086 10000086 10000086 10000088 10000089 10000086 10000085 10000086 10000085 10000086 10000085 10000088 10000087 10000086 10000085 10000087 10000089 10000087 10000085 10000087 10000141 10000085 10000087 10000085 10000086 10000085 10000085 10000088 10000087 10000087 10000088 10000087 10000086 10000085 10000085 10000085 10000085 10000085 10000085 10000088 10000089 10000088 10000085 10000087 10000084 10000085 10000087 10000085 10000085 10000087 10000087 10000086 10000085 10000086 10000088 10000087 10000086 10000085 10000085 10000085 10000085 10000085 10000085 10000088 min_time_thread_terminate_total=10000084 max_time_thread_terminate_total=10000141 first_time_thread_start_by_thread=6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 5 5 5 5 9 6 5 6 5 5 5 5 5 5 5 5 6 6 6 6 5 5 5 6 5 5 5 5 4 6 5 6 5 5 5 5 5 5 5 5 6 6 6 6 5 5 5 6 min_time_thread_start_total=4 max_time_thread_start_total=9 sum_duration_all_ops_total= threads_final_keysum=99712462811995 threads_final_size=9969656 final_keysum=99712462811995 final_size=9969656 validate_result=success Validation OK. Structural validation OK. total_find=370663662 total_rq=0 total_inserts=20594456 total_deletes=20596549 total_updates=41191005 total_queries=370663662 total_ops=411854667 find_throughput=37066366 rq_throughput=0 update_throughput=4119100 query_throughput=37066366 total_throughput=41185466 total find : 370663662 total rq : 0 total inserts : 20594456 total deletes : 20596549 total updates : 41191005 total queries : 370663662 total ops : 411854667 find throughput : 37066366 rq throughput : 0 update throughput : 4119100 query throughput : 37066366 total throughput : 41185466 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=38.6262 PAPI_L3_TCM=14.6619 PAPI_TOT_CYC=6489.38 PAPI_TOT_INS=416.956 garbage=1839385478236469 total_execution_walltime=14.276s REALTIME_MAIN_END_PERF_FORMAT=6983306.142458551 [time_cmd_output] time_elapsed_sec=14.50, faults_major=0, faults_minor=316495, mem_maxresident_kb=1257584, user_cputime=1334.83, sys_cputime=5.11, percent_cpu=9235% 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=2