__step=000329 __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_avl_kcas_validate.debra -nwork 48 -nprefill 48 -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=data000329.txt __path_data=data/data000329.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55b01ed09400 addrof(kcasDescriptors[1])=0x55b01ed09d00 addrof(rdcssDescriptors[0])=0x55b01ee29400 addrof(rdcssDescriptors[1])=0x55b01ee294c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6982729.212169233 binary=./sigouin_int_avl_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_avl_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=0.5 DEL_FRAC=0.5 RQ=0 RQSIZE=0 MAXKEY=200000 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=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.005s prefilling_size=99981 prefilling_elapsed_millis=1.001s finished prefilling to size 99997 for expected size 100000 keysum=9998160538, performing 35402116 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=99997 pref_millis=1102 prefill_elapsed_ms=1132 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6982730.542044746 REALTIME_END_PERF_FORMAT=6982740.542182497 ############################################################################### ################################## 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 1024 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.037s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4095 8120 14461 16729 8537 1443 40 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 1 64 1553 9880 18497 10723 1839 50 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8184 16014 26609 27034 12166 1879 50 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8184 16014 26609 27034 12166 1879 50 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 1.99829 1.96457 1.75859 1.38727 1.13424 1.03584 1.00532 1 tree_stats_height=20 tree_stats_numInternals=57520 tree_stats_numLeaves=42607 tree_stats_numNodes=100127 tree_stats_numKeys=100127 tree_stats_avgDegreeInternal=1.74073 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42553 tree_stats_avgKeyDepth=14.9208 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:176603 2:138731 4:13184 8:9 [2^00, 2^01]: 176603 (2^01, 2^02]: 138731 (2^02, 2^03]: 13184 (2^03, 2^04]: 9 sum_limbo_reclamation_event_size_total=548172 count_limbo_reclamation_event_size_by_thread=6804 6884 6868 6951 6837 6826 6840 6881 6877 6875 6921 6862 6788 6809 6822 6942 6863 6879 6876 6827 6752 6857 6821 6760 6830 6894 6878 6859 6759 6864 6905 6842 6797 6909 6756 6889 6768 6837 6825 6856 6871 6779 6769 6799 6772 6878 6932 6837 count_limbo_reclamation_event_size_total=328527 sum_limbo_reclamation_event_count_total=1943808 first_thread_announced_epoch_by_thread=84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 84416 sum_num_inserts_by_thread=91075 92473 92661 93664 91948 92528 91039 91572 93419 93335 92906 92917 91574 92420 92348 93828 92838 91702 92478 92116 93326 93602 92230 90839 90278 91773 93555 92256 92604 92159 92155 91900 92682 94922 93526 91684 92034 93582 92547 93462 93473 92923 90731 91894 92773 94039 93395 92395 sum_num_inserts_total=4441580 sum_num_deletes_by_thread=91299 93118 92969 93684 92129 92005 90588 91540 93353 93592 92752 92859 91977 92471 92694 94115 93454 92100 92093 91583 92726 93718 92019 90871 91033 92503 93221 92366 92381 91759 92769 92296 92418 94483 93080 91756 91887 93296 93060 93522 93899 92623 90528 92160 92439 93498 93614 92492 sum_num_deletes_total=4442792 sum_num_searches_by_thread=18103383 18408255 18401291 18537623 18296027 18210181 18053125 18112371 18448878 18565555 18451608 18341671 18141989 18283158 18384714 18664849 18483519 18263742 18246228 18178946 18428465 18521796 18212793 18040159 18012048 18169359 18513804 18314219 18399620 18220974 18212107 18214037 18297495 18724621 18442840 18068328 18129378 18481228 18425535 18516026 18541637 18423924 17938223 18231104 18401713 18542887 18516550 18276018 sum_num_searches_total=879794001 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=18285757 18593846 18586921 18724971 18480104 18394714 18234752 18295483 18635650 18752482 18637266 18527447 18325540 18468049 18569756 18852792 18669811 18447544 18430799 18362645 18614517 18709116 18397042 18221869 18193359 18353635 18700580 18498841 18584605 18404892 18397031 18398233 18482595 18914026 18629446 18251768 18313299 18668106 18611142 18703010 18729009 18609470 18119482 18415158 18586925 18730424 18703559 18460905 average_num_operations_total=18514132 stdev_num_operations_total=26652 sum_num_operations_total=888678373 min_num_operations_total=18119482 max_num_operations_total=18914026 first_time_thread_terminate_by_thread=10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000138 10000137 10000137 10000136 10000138 10000136 10000137 10000137 10000137 10000136 10000137 10000138 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000137 10000138 10000137 10000137 10000136 10000137 10000136 10000137 10000137 10000137 10000136 10000137 10000138 10000137 10000137 10000137 min_time_thread_terminate_total=10000136 max_time_thread_terminate_total=10000138 first_time_thread_start_by_thread=3 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=10003225821 threads_final_size=100127 final_keysum=10003225821 final_size=100127 validate_result=success Validation OK. Structural validation OK. total_find=879794001 total_rq=0 total_inserts=4441580 total_deletes=4442792 total_updates=8884372 total_queries=879794001 total_ops=888678373 find_throughput=87979400 rq_throughput=0 update_throughput=888437 query_throughput=87979400 total_throughput=88867837 total find : 879794001 total rq : 0 total inserts : 4441580 total deletes : 4442792 total updates : 8884372 total queries : 879794001 total ops : 888678373 find throughput : 87979400 rq throughput : 0 update throughput : 888437 query throughput : 87979400 total throughput : 88867837 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=5.8732 PAPI_L3_TCM=0.000143653 PAPI_TOT_CYC=1475.49 PAPI_TOT_INS=928.532 garbage=43970374630433 total_execution_walltime=11.442s REALTIME_MAIN_END_PERF_FORMAT=6982740.670764626 [time_cmd_output] time_elapsed_sec=11.59, faults_major=0, faults_minor=76556, mem_maxresident_kb=307980, user_cputime=559.75, sys_cputime=2.67, percent_cpu=4851% timeout=false algorithm=sigouin_int_avl_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=1