__step=000260 __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 0.5 0.5 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000260.txt __path_data=data/data000260.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6981919.664972995 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=0.5 DEL_FRAC=0.5 RQ=0 RQSIZE=0 MAXKEY=200000 PREFILL_THREADS=96 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=96 WORK_THREADS=96 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=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.037s prefilling_size=99842 prefilling_elapsed_millis=1s finished prefilling to size 99829 for expected size 100000 keysum=9974462505, performing 35727705 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=99829 pref_millis=1102 prefill_elapsed_ms=1116 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=934 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=6981920.972613732 REALTIME_END_PERF_FORMAT=6981930.972752556 ############################################################################### ################################## 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 890 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.03s tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 64 127 242 445 784 1323 2121 3174 4437 5799 7163 8205 8943 9179 9042 8633 7783 6601 5390 3965 2713 1757 1075 596 333 161 64 22 4 1 0 tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 0 1 12 39 106 245 525 1068 1911 3075 4435 6121 7467 8707 9316 9451 9483 8965 7812 6815 5217 3669 2439 1554 859 505 258 106 40 7 2 tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 128 254 484 890 1568 2646 4242 6348 8874 11598 14326 16410 17886 18358 18084 17266 15566 13202 10780 7930 5426 3514 2150 1192 666 322 128 44 8 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 1 12 39 106 245 525 1068 1911 3075 4435 6121 7467 8707 9316 9451 9483 8965 7812 6815 5217 3669 2439 1554 859 505 258 106 40 7 2 tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 2 1.99219 1.95276 1.91942 1.8809 1.84375 1.80159 1.74823 1.69896 1.65348 1.61761 1.57273 1.54497 1.51319 1.49254 1.47738 1.45077 1.42407 1.40827 1.36781 1.34212 1.32381 1.30592 1.27721 1.27936 1.24174 1.19876 1.17188 1.09091 1.125 1 tree_stats_height=39 tree_stats_numInternals=100211 tree_stats_numLeaves=100212 tree_stats_numNodes=200423 tree_stats_numKeys=100210 tree_stats_avgDegreeInternal=2.00001 tree_stats_avgDegreeLeaves=0.99998 tree_stats_avgDegree=1.49999 tree_stats_avgKeyDepth=23.2805 tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 5120 10160 19360 35600 62720 105840 169680 253920 354960 463920 573040 656400 715440 734320 723360 690640 622640 528080 431200 317200 217040 140560 86000 47680 26640 12880 5120 1760 320 80 tree_stats_sizeInBytes=8016920 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=21030 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:31635 2:183316 4:494698 8:234394 16:6429 [2^00, 2^01]: 31635 (2^01, 2^02]: 183316 (2^02, 2^03]: 494698 (2^03, 2^04]: 234394 (2^04, 2^05]: 6429 sum_limbo_reclamation_event_size_total=5569507 count_limbo_reclamation_event_size_by_thread=9896 9910 9938 9908 9904 9872 9914 9890 9904 9924 9906 9882 9896 9902 9916 9912 9918 9892 9896 9892 9896 9900 9904 9890 9904 9904 9890 9892 9902 9892 9910 9908 9898 9886 9914 9900 9908 9894 9902 9892 9892 9908 9880 9912 9912 9920 9908 9908 9900 9902 9888 9912 9880 9902 9902 9904 9916 9904 9896 9906 9926 9902 9882 9900 9902 9918 9882 9896 9904 9886 9894 9918 9908 9900 9906 9894 9870 9896 9900 9890 9924 9886 9890 9904 9894 9914 9910 9918 9916 9868 9896 9892 9894 9892 9902 9888 count_limbo_reclamation_event_size_total=950472 sum_limbo_reclamation_event_count_total=3858558 first_thread_announced_epoch_by_thread=42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 42060 sum_num_inserts_by_thread=92721 93901 93829 93246 92870 91695 93268 93585 94275 94617 93815 93242 92296 93777 94306 94479 94091 93949 92873 93269 93960 93931 92795 92346 92182 92792 93966 94676 94123 92721 92366 93763 94459 94346 92696 92677 93068 93511 94652 94869 92212 92974 91628 93334 94864 94992 93866 92975 93078 94015 94501 94927 93904 94533 92954 93187 94175 95277 93171 92815 93052 94001 94033 94448 93781 92372 91824 92840 94405 94299 93478 92945 92507 93238 93829 94640 92990 93376 93176 93960 95412 95363 94877 93782 93385 94041 93913 93716 92332 92104 92024 93247 94261 95532 93201 92946 sum_num_inserts_total=8982715 sum_num_deletes_by_thread=91666 93256 93669 93686 92841 91748 92653 92662 94325 94849 93664 92993 92777 94137 94206 94541 94358 93728 92791 93019 93923 94237 92461 92978 91816 93339 93712 93834 93612 92103 91882 93624 93562 94692 93058 93331 92131 92810 94237 93600 93075 92976 91752 92644 94269 94556 93114 92889 92847 94440 93828 94087 93905 94397 93413 93539 94071 94455 93186 92616 92941 94153 93340 94280 93835 92870 92432 93107 93547 94141 93041 93068 91976 93877 94145 94055 93419 93081 93332 94221 95649 95973 94753 93930 93293 93813 93639 94589 92765 92415 92439 93334 94688 94913 93727 92697 sum_num_deletes_total=8974048 sum_num_searches_by_thread=18267348 18420965 18594942 18494614 18436141 18171694 18353252 18509471 18646045 18805982 18580478 18453577 18354493 18539351 18662052 18745905 18653558 18579775 18343729 18432696 18654267 18686969 18449872 18297415 18216220 18429370 18540727 18582182 18491837 18255698 18293293 18489502 18624864 18740406 18424084 18421157 18369457 18464663 18641087 18685057 18371429 18429016 18152204 18418069 18705040 18769359 18528332 18385765 18388271 18554362 18724091 18723340 18599223 18592273 18505069 18509725 18587519 18783315 18451538 18345628 18407546 18608987 18581048 18686627 18487998 18331066 18275209 18396120 18618552 18596285 18471005 18363364 18225966 18489368 18655114 18710479 18432918 18478555 18424622 18555852 18842600 18988087 18755192 18563578 18418680 18564082 18538097 18621699 18330630 18312284 18216564 18436173 18733219 18784332 18553733 18373722 sum_num_searches_total=1777157116 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=18451735 18608122 18782440 18681546 18621852 18355137 18539173 18695718 18834645 18995448 18767957 18639812 18539566 18727265 18850564 18934925 18842007 18767452 18529393 18618984 18842150 18875137 18635128 18482739 18400218 18615501 18728405 18770692 18679572 18440522 18477541 18676889 18812885 18929444 18609838 18607165 18554656 18650984 18829976 18873526 18556716 18614966 18335584 18604047 18894173 18958907 18715312 18571629 18574196 18742817 18912420 18912354 18787032 18781203 18691436 18696451 18775765 18973047 18637895 18531059 18593539 18797141 18768421 18875355 18675614 18516308 18459465 18582067 18806504 18784725 18657524 18549377 18410449 18676483 18843088 18899174 18619327 18665012 18611130 18744033 19033661 19179423 18944822 18751290 18605358 18751936 18725649 18810004 18515727 18496803 18401027 18622754 18922168 18974777 18740661 18559365 average_num_operations_total=18699102 stdev_num_operations_total=17039 sum_num_operations_total=1795113879 min_num_operations_total=18335584 max_num_operations_total=19179423 first_time_thread_terminate_by_thread=10000140 10000139 10000138 10000140 10000138 10000140 10000139 10000138 10000138 10000139 10000139 10000138 10000141 10000141 10000138 10000139 10000139 10000138 10000140 10000139 10000141 10000139 10000140 10000140 10000140 10000139 10000138 10000140 10000138 10000140 10000139 10000138 10000138 10000139 10000139 10000138 10000141 10000141 10000138 10000139 10000139 10000138 10000140 10000139 10000141 10000139 10000140 10000140 10000140 10000139 10000140 10000139 10000141 10000140 10000140 10000141 10000140 10000139 10000140 10000140 10000141 10000139 10000140 10000141 10000141 10000140 10000141 10000139 10000141 10000139 10000140 10000140 10000140 10000139 10000140 10000140 10000141 10000140 10000140 10000141 10000140 10000139 10000141 10000140 10000141 10000139 10000140 10000141 10000141 10000140 10000141 10000139 10000141 10000139 10000140 10000140 min_time_thread_terminate_total=10000138 max_time_thread_terminate_total=10000141 first_time_thread_start_by_thread=5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 4 5 5 4 5 5 5 4 5 5 4 5 5 5 5 4 5 5 5 5 5 5 5 4 4 5 5 4 5 5 5 4 5 5 4 5 5 5 5 4 min_time_thread_start_total=4 max_time_thread_start_total=5 sum_duration_all_ops_total= threads_final_keysum=10008522813 threads_final_size=100210 final_keysum=10008522813 final_size=100210 validate_result=success Validation OK. Structural validation OK. total_find=1777157116 total_rq=0 total_inserts=8982715 total_deletes=8974048 total_updates=17956763 total_queries=1777157116 total_ops=1795113879 find_throughput=177715711 rq_throughput=0 update_throughput=1795676 query_throughput=177715711 total_throughput=179511387 total find : 1777157116 total rq : 0 total inserts : 8982715 total deletes : 8974048 total updates : 17956763 total queries : 1777157116 total ops : 1795113879 find throughput : 177715711 rq throughput : 0 update throughput : 1795676 query throughput : 177715711 total throughput : 179511387 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=9.93034 PAPI_L3_TCM=0.0778895 PAPI_TOT_CYC=1490.95 PAPI_TOT_INS=314.494 garbage=87949598008618 total_execution_walltime=11.443s REALTIME_MAIN_END_PERF_FORMAT=6981931.109141316 [time_cmd_output] time_elapsed_sec=11.56, faults_major=0, faults_minor=85019, mem_maxresident_kb=331184, user_cputime=1079.66, sys_cputime=5.67, percent_cpu=9386% 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