__step=000330 __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=data000330.txt __path_data=data/data000330.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55a76dc39400 addrof(kcasDescriptors[1])=0x55a76dc39d00 addrof(rdcssDescriptors[0])=0x55a76dd59400 addrof(rdcssDescriptors[1])=0x55a76dd594c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6982740.864727471 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.006s prefilling_size=100181 prefilling_elapsed_millis=1.001s finished prefilling to size 100170 for expected size 100000 keysum=10032039093, performing 35178997 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100170 pref_millis=1102 prefill_elapsed_ms=1124 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6982742.142472248 REALTIME_END_PERF_FORMAT=6982752.142604602 ############################################################################### ################################## 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.039s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8163 14967 17264 7831 974 13 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 29 1220 10352 19852 9972 1207 14 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16187 27616 27683 10946 1220 14 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16187 27616 27683 10946 1220 14 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 1.97949 1.78143 1.37728 1.11252 1.02247 1.00082 1 tree_stats_height=20 tree_stats_numInternals=57403 tree_stats_numLeaves=42646 tree_stats_numNodes=100049 tree_stats_numKeys=100049 tree_stats_avgDegreeInternal=1.74292 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42625 tree_stats_avgKeyDepth=14.8797 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:175615 2:139364 4:13146 8:49 16:6 [2^00, 2^01]: 175615 (2^01, 2^02]: 139364 (2^02, 2^03]: 13146 (2^03, 2^04]: 49 (2^04, 2^05]: 6 sum_limbo_reclamation_event_size_total=549048 count_limbo_reclamation_event_size_by_thread=6820 6802 6793 6864 6856 6828 6745 6843 6906 6881 6906 6818 6893 6882 6752 6800 6827 6830 6717 6874 6805 6887 6930 6812 6840 6867 6866 6867 6856 6823 6811 6839 6882 6899 6910 6758 6704 6786 6854 6939 6945 6783 6765 6837 6855 6834 6787 6802 count_limbo_reclamation_event_size_total=328180 sum_limbo_reclamation_event_count_total=1955088 first_thread_announced_epoch_by_thread=84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 84860 sum_num_inserts_by_thread=92182 92837 94355 93503 94191 91688 92059 92465 94024 94405 93427 93355 92091 94052 92994 93925 92224 94090 92897 94373 93841 94418 93290 92903 93194 92640 94569 94097 93137 92592 93940 93363 94013 94699 94144 92809 91878 92956 93356 94555 92501 94167 92178 93384 94497 94532 93637 93336 sum_num_inserts_total=4483763 sum_num_deletes_by_thread=92713 92951 93596 93879 94464 92264 91959 92568 94614 94770 92868 92683 92785 94325 92970 94004 93352 94149 93443 94434 94039 94299 94161 92743 93273 92696 94320 93623 93314 92677 93307 92563 93791 94363 94311 92627 91964 92903 93588 93811 92360 94160 92330 93424 93867 94953 93286 93525 sum_num_deletes_total=4485069 sum_num_searches_by_thread=18287186 18361170 18657434 18520305 18647719 18204345 18278753 18350476 18588967 18648925 18435790 18417794 18296273 18622625 18475029 18558696 18404148 18591298 18485580 18603493 18592528 18708059 18588561 18445598 18478059 18384893 18663022 18575806 18487139 18363749 18517944 18415403 18638306 18734456 18623279 18356909 18237561 18449317 18471643 18661169 18283578 18571581 18266001 18538298 18547971 18737975 18503958 18516030 sum_num_searches_total=887794799 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=18472081 18546958 18845385 18707687 18836374 18388297 18462771 18535509 18777605 18838100 18622085 18603832 18481149 18811002 18660993 18746625 18589724 18779537 18671920 18792300 18780408 18896776 18776012 18631244 18664526 18570229 18851911 18763526 18673590 18549018 18705191 18601329 18826110 18923518 18811734 18542345 18421403 18635176 18658587 18849535 18468439 18759908 18450509 18725106 18736335 18927460 18690881 18702891 average_num_operations_total=18682575 stdev_num_operations_total=20515 sum_num_operations_total=896763631 min_num_operations_total=18388297 max_num_operations_total=18927460 first_time_thread_terminate_by_thread=10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 10000132 min_time_thread_terminate_total=10000132 max_time_thread_terminate_total=10000132 first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 4 3 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=10021012797 threads_final_size=100049 final_keysum=10021012797 final_size=100049 validate_result=success Validation OK. Structural validation OK. total_find=887794799 total_rq=0 total_inserts=4483763 total_deletes=4485069 total_updates=8968832 total_queries=887794799 total_ops=896763631 find_throughput=88779479 rq_throughput=0 update_throughput=896883 query_throughput=88779479 total_throughput=89676363 total find : 887794799 total rq : 0 total inserts : 4483763 total deletes : 4485069 total updates : 8968832 total queries : 887794799 total ops : 896763631 find throughput : 88779479 rq throughput : 0 update throughput : 896883 query throughput : 88779479 total throughput : 89676363 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=5.8904 PAPI_L3_TCM=0.000220705 PAPI_TOT_CYC=1407.01 PAPI_TOT_INS=926.557 garbage=44402904372571 total_execution_walltime=11.415s REALTIME_MAIN_END_PERF_FORMAT=6982752.280834702 [time_cmd_output] time_elapsed_sec=11.54, faults_major=0, faults_minor=76540, mem_maxresident_kb=307928, user_cputime=563.37, sys_cputime=3.39, percent_cpu=4908% 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=2