__step=000146 __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" ./brown_sigouin_abtree_kcas_validate_htm.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000146.txt __path_data=data/data000146.txt __hostname=jax sizeof(kcasdesc_t)=1920 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55f76fd3c480 addrof(kcasDescriptors[1])=0x55f76fd3cc00 addrof(rdcssDescriptors[0])=0x55f76fe2c480 addrof(rdcssDescriptors[1])=0x55f76fe2c540 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6996134.292175867 binary=./brown_sigouin_abtree_kcas_validate_htm.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_sigouin_abtree_kcas_validate_htm 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=20000000 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=40 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.017s prefilling_size=8934894 prefilling_elapsed_millis=1.001s prefilling_size=9140364 prefilling_elapsed_millis=1.101s prefilling_size=9300473 prefilling_elapsed_millis=1.201s prefilling_size=9429541 prefilling_elapsed_millis=1.302s prefilling_size=9533495 prefilling_elapsed_millis=1.402s prefilling_size=9620677 prefilling_elapsed_millis=1.502s prefilling_size=9689385 prefilling_elapsed_millis=1.602s prefilling_size=9749122 prefilling_elapsed_millis=1.702s prefilling_size=9795230 prefilling_elapsed_millis=1.803s prefilling_size=9834850 prefilling_elapsed_millis=1.903s finished prefilling to size 9834882 for expected size 10000000 keysum=98350050631059, performing 82886314 updates; total_prefilling_elapsed_ms=2004 ms) pref_size=9834882 pref_millis=2004 prefill_elapsed_ms=2026 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6996136.502057095 REALTIME_END_PERF_FORMAT=6996146.502202043 ############################################################################### ################################## 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 3806 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.066s tree_stats_numInternalsAtDepth=1 1 8 68 504 3806 28611 222566 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1751565 tree_stats_numNodesAtDepth=1 1 8 68 504 3806 28611 222566 1751565 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9984225 tree_stats_avgDegreeAtDepth=1 8 8.5 7.41176 7.55159 7.51734 7.77904 7.86987 5.70017 tree_stats_height=9 tree_stats_numInternals=255565 tree_stats_numLeaves=1751565 tree_stats_numNodes=2007130 tree_stats_numKeys=9984225 tree_stats_avgDegreeInternal=7.8537 tree_stats_avgDegreeLeaves=5.70017 tree_stats_avgDegree=5.97438 tree_stats_avgKeyDepth=8 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:52428 2:58111 4:10235 8:368 [2^00, 2^01]: 52428 (2^01, 2^02]: 58111 (2^02, 2^03]: 10235 (2^03, 2^04]: 368 sum_limbo_reclamation_event_size_total=222821 count_limbo_reclamation_event_size_by_thread=2464 2614 2648 2412 2564 2466 2558 2460 2476 2408 2442 2586 2500 2558 2454 2580 2562 2484 2498 2606 2440 2412 2632 2474 2506 2556 2510 2588 2654 2346 2506 2584 2722 2478 2572 2544 2514 2596 2578 2476 2476 2464 2624 2480 2520 2412 2584 2554 count_limbo_reclamation_event_size_total=121142 sum_limbo_reclamation_event_count_total=2054208 first_thread_announced_epoch_by_thread=50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 50440 sum_commit_tx_reader_total= sum_commit_tx_reader_by_thread= sum_commit_tx_writer_total= sum_commit_tx_writer_by_thread= sum_abort_tx_total= sum_abort_tx_by_thread= max_ht_capacity_by_thread= linear_histogram_of_none_tx_rdset_size_full_data= linear_histogram_of_none_tx_wrset_size_full_data= sum_fasthtm_commit_total= sum_fasthtm_abort_total= sum_htmpostfix_commit_total= sum_htmpostfix_abort_total= sum_htmprefix_commit_total= sum_htmprefix_abort_total= sum_htmprefix_abort_explicit_total= sum_htmprefix_abort_conflict_total= sum_htmprefix_abort_capacity_total= sum_htmprefix_abort_nesting_total= sum_htmprefix_abort_illegal_total= sum_htmprefix_abort_zero_total= sum_stmprefix_abort_total= sum_stmprefix_commit_total= sum_slow_commit_total= sum_abtree_insert_repeat_total=55 sum_abtree_erase_repeat_total=8 sum_abtree_search_repeat_total= sum_abtree_search_complete_total=47707965 sum_abtree_fix_weight_viol_attempt_total=187452 sum_abtree_fix_degree_viol_attempt_total=119886 sum_abtree_rebalancing_kcas_attempt_total=307337 sum_abtree_update_kcas_attempt_total=23700278 sum_abtree_internal_complex_search_while_iters_total=47707965 sum_abtree_internal_basic_search_while_iters_total=426532888 sum_kcas_desc_new_total= sum_num_inserts_by_thread=490575 492015 497158 496407 494010 490695 492369 492908 498283 498692 493500 490903 491968 493219 497393 495622 495025 493497 490037 492617 494853 497100 490136 488788 491629 493613 497626 496693 493692 488291 492215 491807 498025 500246 493500 491882 494198 494720 497192 497703 494965 493004 488629 492101 494875 495726 491717 487244 sum_num_inserts_total=23699063 sum_num_deletes_by_thread=492051 494407 496280 496267 494283 489021 490990 492883 497933 499763 494131 492291 491511 494783 498087 496029 493965 492884 488856 492580 495663 495759 490372 488879 491660 492992 496704 495112 494945 488711 492704 491505 498619 498881 495641 491100 493998 495675 497202 497948 494585 492181 490694 490978 494865 495676 491256 488164 sum_num_deletes_total=23701464 sum_num_searches_by_thread=8839146 8879647 8935514 8921395 8898634 8820447 8845212 8883338 8964101 8978240 8885164 8834790 8858813 8893076 8957217 8933169 8899349 8868860 8815590 8862094 8904395 8941269 8843359 8802043 8844730 8876925 8938162 8923340 8903532 8797089 8853367 8851410 8963989 8986958 8894984 8842988 8881421 8912465 8941868 8949272 8903897 8869213 8814717 8855156 8907119 8927971 8844325 8782556 sum_num_searches_total=426532316 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=9821772 9866069 9928952 9914069 9886927 9800163 9828571 9869129 9960317 9976695 9872795 9817984 9842292 9881078 9952697 9924820 9888339 9855241 9794483 9847291 9894911 9934128 9823867 9779710 9828019 9863530 9932492 9915145 9892169 9774091 9838286 9834722 9960633 9986085 9884125 9825970 9869617 9902860 9936262 9944923 9893447 9854398 9794040 9838235 9896859 9919373 9827298 9757964 average_num_operations_total=9873600 stdev_num_operations_total=8199 sum_num_operations_total=473932843 min_num_operations_total=9757964 max_num_operations_total=9986085 first_time_thread_terminate_by_thread=10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000145 10000144 10000144 10000145 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000145 10000144 10000144 10000145 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000144 min_time_thread_terminate_total=10000144 max_time_thread_terminate_total=10000145 first_time_thread_start_by_thread=3 4 4 4 3 3 4 4 3 3 3 3 3 4 4 3 4 3 3 3 3 3 3 3 3 4 4 4 3 3 4 4 3 3 3 3 3 4 4 3 4 3 3 3 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=99835390169908 threads_final_size=9984225 final_keysum=99835390169908 final_size=9984225 validate_result=success Validation OK. Structural validation OK. total_find=426532316 total_rq=0 total_inserts=23699063 total_deletes=23701464 total_updates=47400527 total_queries=426532316 total_ops=473932843 find_throughput=42653231 rq_throughput=0 update_throughput=4740052 query_throughput=42653231 total_throughput=47393284 total find : 426532316 total rq : 0 total inserts : 23699063 total deletes : 23701464 total updates : 47400527 total queries : 426532316 total ops : 473932843 find throughput : 42653231 rq throughput : 0 update throughput : 4740052 query throughput : 42653231 total throughput : 47393284 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=18.4019 PAPI_L3_TCM=6.35987 PAPI_TOT_CYC=2813.58 PAPI_TOT_INS=944.99 garbage=2119459962417478 total_execution_walltime=12.353s REALTIME_MAIN_END_PERF_FORMAT=6996146.659582648 [time_cmd_output] time_elapsed_sec=12.54, faults_major=0, faults_minor=185601, mem_maxresident_kb=742796, user_cputime=609.93, sys_cputime=3.15, percent_cpu=4888% timeout=false algorithm=brown_sigouin_abtree_kcas_validate_htm.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=2