__step=000857 __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 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000857.txt __path_data=data/data000857.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55592aa41400 addrof(kcasDescriptors[1])=0x55592aa41d00 addrof(rdcssDescriptors[0])=0x55592ab61400 addrof(rdcssDescriptors[1])=0x55592ab614c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6989299.950461577 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=2000000 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.007s prefilling_size=1000564 prefilling_elapsed_millis=1.001s finished prefilling to size 1000546 for expected size 1000000 keysum=1001018979292, performing 38035899 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=1000546 pref_millis=1102 prefill_elapsed_ms=1121 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6989301.233866302 REALTIME_END_PERF_FORMAT=6989311.234028045 ############################################################################### ################################## 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 936 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 63 124 245 478 912 1683 2965 4982 7952 12136 17572 23990 31073 38303 45227 50860 54334 55802 55042 51799 46442 39882 32676 25288 18708 13290 8995 5951 3627 2032 1044 515 212 95 44 16 4 2 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 1 1 1 7 24 82 225 534 1110 2088 3580 5971 9072 12874 16763 21147 25347 28091 30058 30796 30209 27817 24701 21187 16567 12469 9202 6260 4215 2716 1549 795 425 160 73 37 15 3 3 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 125 246 485 936 1765 3190 5516 9062 14224 21152 29961 40145 51177 61990 72007 79681 83893 85100 82595 76651 67699 57377 46475 35275 25759 18197 12211 7842 4748 2593 1310 637 255 117 53 19 5 3 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 125 246 485 936 1765 3190 5516 9062 14224 21152 29961 40145 51177 61990 72007 79681 83893 85100 82595 76651 67699 57377 46475 35275 25759 18197 12211 7842 4748 2593 1310 637 255 117 53 19 5 3 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.96875 1.976 1.97561 1.94433 1.91132 1.85382 1.79969 1.73967 1.69212 1.63386 1.58571 1.5392 1.50078 1.46284 1.43201 1.40025 1.37097 1.34923 1.32377 1.30089 1.27732 1.25842 1.2405 1.21489 1.19989 1.1905 1.17673 1.15486 1.14295 1.11816 1.10258 1.09313 1.0675 1.08627 1.07692 1.0566 1.05263 1.2 1 tree_stats_height=45 tree_stats_numInternals=654428 tree_stats_numLeaves=346175 tree_stats_numNodes=1000603 tree_stats_numKeys=1000603 tree_stats_avgDegreeInternal=1.52897 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34597 tree_stats_avgKeyDepth=23.7909 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:2 16:2 32:204 64:454926 128:24770 (2^03, 2^04]: 2 (2^04, 2^05]: 2 (2^05, 2^06]: 204 (2^06, 2^07]: 454926 (2^07, 2^08]: 24770 sum_limbo_reclamation_event_size_total=53916306 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 count_limbo_reclamation_event_size_total=479904 sum_limbo_reclamation_event_count_total=799056 first_thread_announced_epoch_by_thread=36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 36824 sum_num_inserts_by_thread=3706508 3742854 3756281 3750091 3745300 3703347 3717271 3731817 3775095 3784651 3748107 3728490 3727259 3758957 3760845 3769823 3753403 3730981 3712871 3744038 3762032 3779488 3738110 3709127 3721153 3738088 3749773 3755501 3734717 3708938 3706372 3741895 3778082 3778383 3740366 3724147 3733623 3753527 3770993 3771970 3757097 3731753 3716464 3735943 3751037 3784680 3649650 3715313 sum_num_inserts_total=179586211 sum_num_deletes_by_thread=3706053 3740320 3755433 3751154 3743458 3703265 3712770 3734649 3773185 3783077 3747177 3726994 3725536 3758455 3761637 3772081 3753736 3734684 3717182 3742820 3755685 3779977 3737836 3711353 3721763 3737320 3748239 3756742 3737648 3711609 3709574 3741097 3776594 3778237 3740705 3725559 3731552 3754478 3772742 3773464 3755923 3730302 3712904 3734065 3749791 3780822 3648892 3716693 sum_num_deletes_total=179575232 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=7412561 7483174 7511714 7501245 7488758 7406612 7430041 7466466 7548280 7567728 7495284 7455484 7452795 7517412 7522482 7541904 7507139 7465665 7430053 7486858 7517717 7559465 7475946 7420480 7442916 7475408 7498012 7512243 7472365 7420547 7415946 7482992 7554676 7556620 7481071 7449706 7465175 7508005 7543735 7545434 7513020 7462055 7429368 7470008 7500828 7565502 7298542 7432006 average_num_operations_total=7482530 stdev_num_operations_total=7636 sum_num_operations_total=359161443 min_num_operations_total=7298542 max_num_operations_total=7567728 first_time_thread_terminate_by_thread=10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 10000162 min_time_thread_terminate_total=10000162 max_time_thread_terminate_total=10000162 first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=1001289852468 threads_final_size=1000603 final_keysum=1001289852468 final_size=1000603 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=179586211 total_deletes=179575232 total_updates=359161443 total_queries=0 total_ops=359161443 find_throughput=0 rq_throughput=0 update_throughput=35916144 query_throughput=0 total_throughput=35916144 total find : 0 total rq : 0 total inserts : 179586211 total deletes : 179575232 total updates : 359161443 total queries : 0 total ops : 359161443 find throughput : 0 rq throughput : 0 update throughput : 35916144 query throughput : 0 total throughput : 35916144 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=15.6062 PAPI_L3_TCM=2.39304 PAPI_TOT_CYC=3682.79 PAPI_TOT_INS=1961.65 garbage=0 total_execution_walltime=11.428s REALTIME_MAIN_END_PERF_FORMAT=6989311.379399428 [time_cmd_output] time_elapsed_sec=11.55, faults_major=0, faults_minor=92313, mem_maxresident_kb=370564, user_cputime=559.44, sys_cputime=2.95, percent_cpu=4869% 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=1