__step=000634 __cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 180 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 5.0 5.0 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000634.txt __path_data=data/data000634.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x558ddd1e2400 addrof(kcasDescriptors[1])=0x558ddd1e2d00 addrof(rdcssDescriptors[0])=0x558ddd302400 addrof(rdcssDescriptors[1])=0x558ddd3024c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=7002431.537149564 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=5 DEL_FRAC=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=5.0 5.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.005s prefilling_size=99843 prefilling_elapsed_millis=1.001s finished prefilling to size 99844 for expected size 100000 keysum=9995428270, performing 35151031 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=99844 pref_millis=1102 prefill_elapsed_ms=1123 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=7002432.818345619 REALTIME_END_PERF_FORMAT=7002442.818490997 ############################################################################### ################################## 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.031s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4095 8117 14429 17197 8435 908 13 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 1 68 1578 9496 19380 10922 1122 17 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8185 16007 26693 27815 11830 1135 17 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8185 16007 26693 27815 11830 1135 17 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 1.99854 1.96396 1.76616 1.39778 1.12206 1.01919 1.00352 1 tree_stats_height=20 tree_stats_numInternals=57289 tree_stats_numLeaves=42584 tree_stats_numNodes=99873 tree_stats_numKeys=99873 tree_stats_avgDegreeInternal=1.74332 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42638 tree_stats_avgKeyDepth=14.8981 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:85 2:1680 4:58763 8:380720 16:38490 32:39 64:62 128:0 256:47 [2^00, 2^01]: 85 (2^01, 2^02]: 1680 (2^02, 2^03]: 58763 (2^03, 2^04]: 380720 (2^04, 2^05]: 38490 (2^05, 2^06]: 39 (2^06, 2^07]: 62 (2^07, 2^08]: 0 (2^08, 2^09]: 47 sum_limbo_reclamation_event_size_total=5308966 count_limbo_reclamation_event_size_by_thread=9997 9998 9998 9998 9998 9998 9998 9998 9998 9996 9997 9997 9998 9997 9998 9998 9998 9997 9998 9998 9997 9997 9998 9997 9998 9997 9998 9998 9998 9997 9998 9998 9998 9997 9998 9998 9998 9996 9997 9998 9998 9998 9998 9998 9997 9998 9998 9997 count_limbo_reclamation_event_size_total=479886 sum_limbo_reclamation_event_count_total=1734911 first_thread_announced_epoch_by_thread=75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 75698 sum_num_inserts_by_thread=790147 800673 804555 804662 803146 789004 789668 798127 804090 807532 801133 793455 792928 797951 796890 798111 801892 799105 793534 799722 803158 808584 797692 791681 791778 796718 803677 806183 801610 792291 792995 795492 804030 808475 796706 795770 789068 796767 800282 809110 801305 800631 788956 795819 801358 806021 803386 796682 sum_num_inserts_total=38342550 sum_num_deletes_by_thread=789435 799641 803900 804704 804065 787909 791348 796940 806512 809749 801433 794586 793764 796896 796276 799767 802327 800609 795134 800030 802690 807179 798046 789179 791330 795620 804127 807418 803892 790407 795126 794558 805858 809912 799270 794780 788406 796261 799870 809459 801180 799154 789082 796174 801231 805821 801890 797243 sum_num_deletes_total=38350188 sum_num_searches_by_thread=14208399 14403810 14458695 14492535 14454375 14201664 14256375 14343071 14492006 14565038 14419456 14289615 14274495 14341535 14345692 14390235 14430106 14386618 14307521 14405783 14451123 14518829 14357336 14237411 14252925 14328806 14448718 14529759 14459381 14236800 14303805 14303955 14489619 14558833 14357810 14314507 14195312 14356368 14383821 14564041 14420863 14385669 14196402 14324417 14430127 14492590 14462171 14349494 sum_num_searches_total=690177916 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=15787981 16004124 16067150 16101901 16061586 15778577 15837391 15938138 16102608 16182319 16022022 15877656 15861187 15936382 15938858 15988113 16034325 15986332 15896189 16005535 16056971 16134592 15953074 15818271 15836033 15921144 16056522 16143360 16064883 15819498 15891926 15894005 16099507 16177220 15953786 15905057 15772786 15949396 15983973 16182610 16023348 15985454 15774440 15916410 16032716 16104432 16067447 15943419 average_num_operations_total=15976471 stdev_num_operations_total=16572 sum_num_operations_total=766870654 min_num_operations_total=15772786 max_num_operations_total=16182610 first_time_thread_terminate_by_thread=10000143 10000144 10000143 10000143 10000144 10000144 10000143 10000143 10000146 10000143 10000144 10000143 10000144 10000144 10000143 10000144 10000143 10000143 10000144 10000143 10000144 10000144 10000144 10000147 10000143 10000144 10000143 10000143 10000144 10000144 10000143 10000143 10000144 10000143 10000144 10000143 10000144 10000144 10000143 10000144 10000143 10000143 10000144 10000143 10000144 10000144 10000144 10000144 min_time_thread_terminate_total=10000143 max_time_thread_terminate_total=10000147 first_time_thread_start_by_thread=4 4 4 4 4 4 4 3 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 3 4 4 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=9963444747 threads_final_size=99873 final_keysum=9963444747 final_size=99873 validate_result=success Validation OK. Structural validation OK. total_find=690177916 total_rq=0 total_inserts=38342550 total_deletes=38350188 total_updates=76692738 total_queries=690177916 total_ops=766870654 find_throughput=69017791 rq_throughput=0 update_throughput=7669273 query_throughput=69017791 total_throughput=76687065 total find : 690177916 total rq : 0 total inserts : 38342550 total deletes : 38350188 total updates : 76692738 total queries : 690177916 total ops : 766870654 find throughput : 69017791 rq throughput : 0 update throughput : 7669273 query throughput : 69017791 total throughput : 76687065 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=6.45939 PAPI_L3_TCM=0.000200095 PAPI_TOT_CYC=1706.16 PAPI_TOT_INS=1094.67 garbage=34506421434798 total_execution_walltime=11.413s REALTIME_MAIN_END_PERF_FORMAT=7002442.950956858 [time_cmd_output] time_elapsed_sec=11.53, faults_major=0, faults_minor=77010, mem_maxresident_kb=309720, user_cputime=561.47, sys_cputime=2.86, percent_cpu=4890% 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