__step=000153 __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" ./guerraoui_ext_bst_ticket.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=data000153.txt __path_data=data/data000153.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6980667.145621582 binary=./guerraoui_ext_bst_ticket.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=guerraoui_ext_bst_ticket 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=40 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.021s prefilling_size=999609 prefilling_elapsed_millis=1.001s finished prefilling to size 999574 for expected size 1000000 keysum=999385729180, performing 32581111 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=999574 pref_millis=1102 prefill_elapsed_ms=1123 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=1510 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6980668.434109333 REALTIME_END_PERF_FORMAT=6980678.434259684 ############################################################################### ################################## 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 1120 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.04s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 60 109 182 315 560 1011 1827 3238 5533 9035 13997 20544 28484 37557 47015 56671 65993 74050 79658 82826 82507 78209 71171 61987 51287 40407 30306 21415 14229 8848 5285 2914 1558 767 321 120 52 15 4 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 4 11 36 49 70 109 195 416 943 2031 4073 7450 12604 19411 28099 37359 47349 57936 68442 76490 83145 86805 85247 80355 72687 62167 50508 39197 28601 19610 12411 7656 4270 2349 1213 522 188 89 26 7 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 120 218 364 630 1120 2022 3654 6476 11066 18070 27994 41088 56968 75114 94030 113342 131986 148100 159316 165652 165014 156418 142342 123974 102574 80814 60612 42830 28458 17696 10570 5828 3116 1534 642 240 104 30 8 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 4 11 36 49 70 109 195 416 943 2030 4073 7450 12604 19411 28099 37359 47349 57936 68442 76490 83145 86805 85247 80355 72687 62167 50508 39197 28601 19610 12411 7656 4270 2349 1213 522 188 89 26 7 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.9375 1.90833 1.83486 1.86538 1.88889 1.90268 1.90356 1.88615 1.85439 1.81637 1.7746 1.73387 1.69324 1.65926 1.62592 1.60269 1.58225 1.56104 1.53787 1.51989 1.49807 1.47395 1.45501 1.43548 1.41369 1.39393 1.37501 1.35331 1.33222 1.31091 1.29866 1.27569 1.26733 1.24615 1.20926 1.18692 1.21667 1.14423 1.13333 1.125 1 tree_stats_height=47 tree_stats_numInternals=1000131 tree_stats_numLeaves=1000132 tree_stats_numNodes=2000263 tree_stats_numKeys=1000131 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=0.999999 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=27.0798 tree_stats_bytesAtDepth=40 80 160 320 640 1280 2560 4800 8720 14560 25200 44800 80880 146160 259040 442640 722800 1119760 1643520 2278720 3004560 3761200 4533680 5279440 5924000 6372640 6626080 6600560 6256720 5693680 4958960 4102960 3232560 2424480 1713200 1138320 707840 422800 233120 124640 61360 25680 9600 4160 1200 320 80 tree_stats_sizeInBytes=80010520 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=19508 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:175298 4:138569 8:13931 16:10 (2^01, 2^02]: 175298 (2^02, 2^03]: 138569 (2^03, 2^04]: 13931 (2^04, 2^05]: 10 sum_limbo_reclamation_event_size_total=1099958 count_limbo_reclamation_event_size_by_thread=6838 6826 6888 6848 6871 6774 6835 6816 6872 6719 6796 6896 6763 6844 6839 6948 6824 6798 6840 6846 6705 6909 6820 6754 6738 6866 6818 6880 6800 6719 6793 6894 6872 6867 6834 6801 6831 6848 6852 6865 6855 6794 6889 6721 6785 6890 6878 6849 count_limbo_reclamation_event_size_total=327808 sum_limbo_reclamation_event_count_total=863904 first_thread_announced_epoch_by_thread=39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 39016 sum_num_inserts_by_thread=41009 41054 41300 41200 41329 40883 40842 41484 41724 41961 41326 41013 41315 41297 41596 41706 41425 41174 40845 40681 41586 41684 41010 40815 40701 41276 41581 41204 41232 40985 41211 41501 41652 41566 41442 41214 41077 41534 41559 41881 41309 41162 40821 41037 41531 41237 41179 40875 sum_num_inserts_total=1981026 sum_num_deletes_by_thread=40829 41192 41668 41584 41275 40651 40830 41137 41722 41735 41046 41685 40794 41695 41573 42089 41357 41016 40874 41233 40997 41548 41564 40703 40280 41351 41571 41286 41439 40518 40932 41367 41971 41771 41354 41148 41151 41357 41481 41595 41211 40866 41147 40972 41143 41917 40720 40646 sum_num_deletes_total=1979991 sum_num_searches_by_thread=8093886 8138771 8219405 8214456 8182526 8067441 8111897 8165262 8257234 8278454 8166072 8131194 8127907 8187663 8234820 8247369 8203673 8151512 8087594 8151999 8201912 8249178 8151002 8080533 8085686 8150771 8217909 8218013 8168474 8085168 8127642 8168120 8265312 8277265 8173170 8129039 8143253 8189964 8246419 8261990 8206404 8151352 8079892 8137280 8193014 8243176 8137413 8087867 sum_num_searches_total=392246353 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=8175724 8221017 8302373 8297240 8265130 8148975 8193569 8247883 8340680 8362150 8248444 8213892 8210016 8270655 8317989 8331164 8286455 8233702 8169313 8233913 8284495 8332410 8233576 8162051 8166667 8233398 8301061 8300503 8251145 8166671 8209785 8250988 8348935 8360602 8255966 8211401 8225481 8272855 8329459 8345466 8288924 8233380 8161860 8219289 8275688 8326330 8219312 8169388 average_num_operations_total=8254320 stdev_num_operations_total=8752 sum_num_operations_total=396207370 min_num_operations_total=8148975 max_num_operations_total=8362150 first_time_thread_terminate_by_thread=10000150 10000149 10000149 10000149 10000150 10000150 10000149 10000150 10000150 10000150 10000150 10000150 10000150 10000149 10000150 10000150 10000150 10000150 10000149 10000150 10000149 10000150 10000150 10000150 10000150 10000149 10000149 10000149 10000150 10000150 10000149 10000150 10000150 10000150 10000150 10000150 10000150 10000149 10000150 10000150 10000150 10000150 10000149 10000150 10000149 10000150 10000150 10000150 min_time_thread_terminate_total=10000149 max_time_thread_terminate_total=10000150 first_time_thread_start_by_thread=3 4 4 3 3 4 4 3 4 3 3 4 4 3 3 4 4 4 3 4 3 3 3 3 3 4 4 3 3 4 4 3 4 3 3 4 4 3 3 4 4 4 3 4 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=999577884904 threads_final_size=1000131 final_keysum=999577884904 final_size=1000131 validate_result=success Validation OK. Structural validation OK. total_find=392246353 total_rq=0 total_inserts=1981026 total_deletes=1979991 total_updates=3961017 total_queries=392246353 total_ops=396207370 find_throughput=39224635 rq_throughput=0 update_throughput=396101 query_throughput=39224635 total_throughput=39620737 total find : 392246353 total rq : 0 total inserts : 1981026 total deletes : 1979991 total updates : 3961017 total queries : 392246353 total ops : 396207370 find throughput : 39224635 rq throughput : 0 update throughput : 396101 query throughput : 39224635 total throughput : 39620737 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=20.9874 PAPI_L3_TCM=3.99798 PAPI_TOT_CYC=3376.87 PAPI_TOT_INS=319.685 garbage=196206511801817 total_execution_walltime=11.419s REALTIME_MAIN_END_PERF_FORMAT=6980678.566319158 [time_cmd_output] time_elapsed_sec=11.54, faults_major=0, faults_minor=98854, mem_maxresident_kb=397508, user_cputime=561.29, sys_cputime=1.97, percent_cpu=4878% timeout=false algorithm=guerraoui_ext_bst_ticket.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1