__step=000746 __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" ./sigouin_int_bst_kcas_validate.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000746.txt __path_data=data/data000746.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x564c95bf4400 addrof(kcasDescriptors[1])=0x564c95bf4d00 addrof(rdcssDescriptors[0])=0x564c95d14400 addrof(rdcssDescriptors[1])=0x564c95d144c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6987928.968514128 binary=./sigouin_int_bst_kcas_validate.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=sigouin_int_bst_kcas_validate 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=50 DEL_FRAC=50 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=50.0 50.0 PREFILL_TYPE=PREFILL_HYBRID PREFILL_HYBRID_MIN_MS=1000 PREFILL_HYBRID_MAX_MS=5000 sizes: node=56 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.009s prefilling_size=6992928 prefilling_elapsed_millis=1.001s prefilling_size=7283668 prefilling_elapsed_millis=1.101s prefilling_size=7546531 prefilling_elapsed_millis=1.201s prefilling_size=7782761 prefilling_elapsed_millis=1.301s prefilling_size=7994072 prefilling_elapsed_millis=1.402s prefilling_size=8183444 prefilling_elapsed_millis=1.502s prefilling_size=8355894 prefilling_elapsed_millis=1.602s prefilling_size=8511075 prefilling_elapsed_millis=1.702s prefilling_size=8651069 prefilling_elapsed_millis=1.802s prefilling_size=8777661 prefilling_elapsed_millis=1.902s prefilling_size=8892713 prefilling_elapsed_millis=2.003s prefilling_size=8997144 prefilling_elapsed_millis=2.103s prefilling_size=9092473 prefilling_elapsed_millis=2.203s prefilling_size=9175455 prefilling_elapsed_millis=2.303s prefilling_size=9251854 prefilling_elapsed_millis=2.403s prefilling_size=9322007 prefilling_elapsed_millis=2.504s prefilling_size=9387537 prefilling_elapsed_millis=2.604s prefilling_size=9444059 prefilling_elapsed_millis=2.704s prefilling_size=9494547 prefilling_elapsed_millis=2.804s prefilling_size=9541557 prefilling_elapsed_millis=2.904s prefilling_size=9582695 prefilling_elapsed_millis=3.005s prefilling_size=9621063 prefilling_elapsed_millis=3.105s prefilling_size=9657310 prefilling_elapsed_millis=3.205s prefilling_size=9689305 prefilling_elapsed_millis=3.305s prefilling_size=9716706 prefilling_elapsed_millis=3.405s prefilling_size=9741937 prefilling_elapsed_millis=3.506s prefilling_size=9764694 prefilling_elapsed_millis=3.606s prefilling_size=9786830 prefilling_elapsed_millis=3.706s prefilling_size=9806934 prefilling_elapsed_millis=3.806s finished prefilling to size 9806945 for expected size 10000000 keysum=98086190625035, performing 80188524 updates; total_prefilling_elapsed_ms=3907 ms) pref_size=9806945 pref_millis=3907 prefill_elapsed_ms=3930 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6987933.97673251 REALTIME_END_PERF_FORMAT=6987943.97816008 ############################################################################### ################################## 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 1003 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.099s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 506 991 1927 3657 6756 12111 20886 34649 55326 84378 122840 171274 227347 288663 351787 410750 462362 499201 519195 518438 497556 458679 406420 347210 284172 224238 169924 123983 87026 58798 38103 23536 14002 8012 4421 2386 1241 615 277 122 43 27 12 4 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 2 12 32 105 320 748 1860 3879 7630 14331 24942 40498 62202 88873 120640 156720 190928 226124 254044 275133 284794 282889 268712 244140 214926 179755 145124 112190 83699 60134 41127 27275 16972 10220 6000 3282 1800 943 493 223 104 29 20 9 4 1 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 508 1003 1959 3762 7076 12859 22746 38528 62956 98709 147782 211772 289549 377536 472427 567470 653290 725325 773239 793571 782350 741568 675132 591350 499098 403993 315048 236173 170725 118932 79230 50811 30974 18232 10421 5668 3041 1558 770 345 147 56 32 13 5 1 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 508 1003 1959 3762 7076 12859 22746 38528 62956 98709 147782 211772 289549 377536 472427 567470 653290 725325 773239 793571 782350 741568 675132 591350 499098 403993 315048 236173 170725 118932 79230 50811 30974 18232 10421 5668 3041 1558 770 345 147 56 32 13 5 1 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.98828 1.97835 1.9651 1.9367 1.90883 1.86249 1.82705 1.77561 1.73471 1.6891 1.64233 1.60178 1.5585 1.5187 1.48675 1.45654 1.42741 1.40252 1.37781 1.35484 1.33256 1.3119 1.29189 1.27392 1.25685 1.24008 1.22478 1.21028 1.19791 1.18689 1.1718 1.16039 1.14639 1.13657 1.13213 1.11966 1.11556 1.10424 1.09949 1.08831 1.07246 1.08844 1.08929 1.03125 1.07692 1 1 tree_stats_height=55 tree_stats_numInternals=6544362 tree_stats_numLeaves=3453889 tree_stats_numNodes=9998251 tree_stats_numKeys=9998251 tree_stats_avgDegreeInternal=1.52777 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34545 tree_stats_avgKeyDepth=28.1902 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:0 16:0 32:41 64:408733 128:28506 (2^05, 2^06]: 41 (2^06, 2^07]: 408733 (2^07, 2^08]: 28506 sum_limbo_reclamation_event_size_total=49548586 count_limbo_reclamation_event_size_by_thread=9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 9110 count_limbo_reclamation_event_size_total=437280 sum_limbo_reclamation_event_count_total=437376 first_thread_announced_epoch_by_thread=25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 25538 sum_num_inserts_by_thread=2058546 2066520 2080242 2072066 2068125 2049912 2058076 2069862 2087912 2085786 2065872 2058955 2060199 2073595 2085188 2085261 2076563 2064023 2054064 2064182 2074792 2081633 2059557 2050717 2054890 2065584 2081328 2075876 2067692 2054820 2063815 2068944 2086021 2086756 2065976 2058732 2065188 2073368 2082490 2085604 2072407 2060460 2051526 2062722 2073517 2082958 2061625 2049855 sum_num_inserts_total=99303802 sum_num_deletes_by_thread=2054539 2063949 2082790 2074680 2068428 2053362 2059971 2069667 2086387 2086835 2066601 2057207 2062667 2072765 2084044 2084178 2075627 2061690 2052492 2064269 2075416 2080983 2060257 2049826 2056096 2066764 2080382 2073749 2068604 2051663 2060841 2069962 2085841 2087503 2064905 2057108 2064123 2073962 2082762 2085971 2072819 2062134 2052116 2062196 2070276 2082997 2061582 2050541 sum_num_deletes_total=99293527 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=4113085 4130469 4163032 4146746 4136553 4103274 4118047 4139529 4174299 4172621 4132473 4116162 4122866 4146360 4169232 4169439 4152190 4125713 4106556 4128451 4150208 4162616 4119814 4100543 4110986 4132348 4161710 4149625 4136296 4106483 4124656 4138906 4171862 4174259 4130881 4115840 4129311 4147330 4165252 4171575 4145226 4122594 4103642 4124918 4143793 4165955 4123207 4100396 average_num_operations_total=4137444 stdev_num_operations_total=3292 sum_num_operations_total=198597329 min_num_operations_total=4100396 max_num_operations_total=4174299 first_time_thread_terminate_by_thread=10000142 10000142 10000143 10000144 10000142 10000142 10000142 10000142 10000142 10000142 10000143 10000142 10000142 10000142 10000142 10000142 10000142 10000142 10000144 10000142 10000142 10000143 10000142 10000143 10000143 10000142 10000143 10000142 10000143 10000142 10000143 10000144 10000142 10000142 10000143 10000142 10000142 10000143 10000142 10000142 10000142 10000142 10000143 10000143 10000142 10000142 10000142 10000142 min_time_thread_terminate_total=10000142 max_time_thread_terminate_total=10000144 first_time_thread_start_by_thread=2 2 3 3 3 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 2 3 3 3 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=99978915624364 threads_final_size=9998251 final_keysum=99978915624364 final_size=9998251 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=99303802 total_deletes=99293527 total_updates=198597329 total_queries=0 total_ops=198597329 find_throughput=0 rq_throughput=0 update_throughput=19859732 query_throughput=0 total_throughput=19859732 total find : 0 total rq : 0 total inserts : 99303802 total deletes : 99293527 total updates : 198597329 total queries : 0 total ops : 198597329 find throughput : 0 rq throughput : 0 update throughput : 19859732 query throughput : 0 total throughput : 19859732 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=28.0066 PAPI_L3_TCM=9.20977 PAPI_TOT_CYC=6738.36 PAPI_TOT_INS=2181.13 garbage=0 total_execution_walltime=14.34s REALTIME_MAIN_END_PERF_FORMAT=6987943.309913071 [time_cmd_output] time_elapsed_sec=14.52, faults_major=0, faults_minor=239005, mem_maxresident_kb=957876, user_cputime=700.88, sys_cputime=3.24, percent_cpu=4848% timeout=false algorithm=sigouin_int_bst_kcas_validate.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=2