__step=000569 __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.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=data000569.txt __path_data=data/data000569.txt __hostname=jax sizeof(kcasdesc_t)=1920 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x563a92475480 addrof(kcasDescriptors[1])=0x563a92475c00 addrof(rdcssDescriptors[0])=0x563a92565480 addrof(rdcssDescriptors[1])=0x563a92565540 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=7001669.139769851 binary=./brown_sigouin_abtree_kcas_validate.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 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=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.005s prefilling_size=100458 prefilling_elapsed_millis=1.001s finished prefilling to size 100424 for expected size 100000 keysum=10034070513, performing 76727539 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100424 pref_millis=1102 prefill_elapsed_ms=1124 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=7001670.450577164 REALTIME_END_PERF_FORMAT=7001680.450717607 ############################################################################### ################################## 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 3146 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.05s tree_stats_numInternalsAtDepth=1 1 8 55 416 3146 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 19526 tree_stats_numNodesAtDepth=1 1 8 55 416 3146 19526 tree_stats_numKeysAtDepth=0 0 0 0 0 0 100381 tree_stats_avgDegreeAtDepth=1 8 6.875 7.56364 7.5625 6.20661 5.14089 tree_stats_height=7 tree_stats_numInternals=3627 tree_stats_numLeaves=19526 tree_stats_numNodes=23153 tree_stats_numKeys=100381 tree_stats_avgDegreeInternal=6.38351 tree_stats_avgDegreeLeaves=5.14089 tree_stats_avgDegree=5.33555 tree_stats_avgKeyDepth=6 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:62944 2:77879 4:11148 8:81 [2^00, 2^01]: 62944 (2^01, 2^02]: 77879 (2^02, 2^03]: 11148 (2^03, 2^04]: 81 sum_limbo_reclamation_event_size_total=272020 count_limbo_reclamation_event_size_by_thread=3280 3160 3072 3256 3138 3124 3198 3104 3022 3276 3244 3140 3228 3182 3014 3222 3198 3204 3038 3310 3196 3206 3100 3172 3148 3156 3248 3316 3098 3164 3062 3120 3106 3202 3082 3106 3186 3152 3102 3132 3172 3238 3256 3150 3160 3360 3146 3106 count_limbo_reclamation_event_size_total=152052 sum_limbo_reclamation_event_count_total=4927480 first_thread_announced_epoch_by_thread=109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 109744 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=6294 sum_abtree_erase_repeat_total=985 sum_abtree_search_repeat_total=333 sum_abtree_search_complete_total=113835888 sum_abtree_fix_weight_viol_attempt_total=106710 sum_abtree_fix_degree_viol_attempt_total=822228 sum_abtree_rebalancing_kcas_attempt_total=927800 sum_abtree_update_kcas_attempt_total=56451406 sum_abtree_internal_complex_search_while_iters_total=113836804 sum_abtree_internal_basic_search_while_iters_total=1016143923 sum_kcas_desc_new_total= sum_num_inserts_by_thread=1171664 1175288 1183214 1182836 1179148 1165921 1177005 1179933 1186419 1193782 1176111 1173475 1172150 1181965 1180911 1176847 1175111 1171975 1165375 1171542 1177878 1185639 1169015 1165157 1166785 1169623 1181523 1178012 1175291 1168038 1164494 1169892 1184405 1186734 1178380 1170263 1176039 1177441 1180099 1184090 1174138 1171051 1163062 1177342 1180777 1184999 1178781 1162980 sum_num_inserts_total=56442600 sum_num_deletes_by_thread=1175280 1174161 1182607 1182701 1181130 1165988 1175292 1177633 1186750 1192033 1175259 1172833 1174074 1182691 1181038 1175881 1176577 1172520 1166330 1173130 1181326 1185116 1169094 1164636 1166333 1170173 1183255 1179598 1176973 1168449 1163691 1171371 1183699 1186321 1177477 1174016 1174961 1178217 1180893 1182884 1173078 1172979 1164919 1176751 1180769 1183236 1177727 1162896 sum_num_deletes_total=56454746 sum_num_searches_by_thread=21161090 21133801 21270583 21305469 21251321 20975732 21173422 21244296 21362709 21448441 21164330 21105829 21101608 21271140 21268357 21175338 21133309 21110152 21005627 21108794 21229006 21320922 21051751 20976675 21006067 21071476 21275719 21234819 21177901 21036547 20941119 21066067 21317212 21348665 21214964 21096849 21135537 21210473 21251772 21301197 21125437 21088102 20962473 21190750 21230919 21303348 21213936 20954000 sum_num_searches_total=1016105051 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=23508034 23483250 23636404 23671006 23611599 23307641 23525719 23601862 23735878 23834256 23515700 23452137 23447832 23635796 23630306 23528066 23484997 23454647 23337332 23453466 23588210 23691677 23389860 23306468 23339185 23411272 23640497 23592429 23530165 23373034 23269304 23407330 23685316 23721720 23570821 23441128 23486537 23566131 23612764 23668171 23472653 23432132 23290454 23544843 23592465 23671583 23570444 23279876 average_num_operations_total=23520883 stdev_num_operations_total=19531 sum_num_operations_total=1129002397 min_num_operations_total=23269304 max_num_operations_total=23834256 first_time_thread_terminate_by_thread=10000140 10000139 10000139 10000140 10000140 10000140 10000139 10000139 10000140 10000139 10000139 10000140 10000140 10000140 10000140 10000140 10000139 10000140 10000139 10000139 10000140 10000140 10000139 10000139 10000140 10000139 10000139 10000140 10000140 10000140 10000139 10000139 10000140 10000139 10000139 10000140 10000140 10000140 10000140 10000139 10000139 10000140 10000139 10000139 10000140 10000140 10000139 10000139 min_time_thread_terminate_total=10000139 max_time_thread_terminate_total=10000140 first_time_thread_start_by_thread=2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 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=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=10026095724 threads_final_size=100381 final_keysum=10026095724 final_size=100381 validate_result=success Validation OK. Structural validation OK. total_find=1016105051 total_rq=0 total_inserts=56442600 total_deletes=56454746 total_updates=112897346 total_queries=1016105051 total_ops=1129002397 find_throughput=101610505 rq_throughput=0 update_throughput=11289734 query_throughput=101610505 total_throughput=112900239 total find : 1016105051 total rq : 0 total inserts : 56442600 total deletes : 56454746 total updates : 112897346 total queries : 1016105051 total ops : 1129002397 find throughput : 101610505 rq throughput : 0 update throughput : 11289734 query throughput : 101610505 total throughput : 112900239 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=5.90462 PAPI_L3_TCM=9.29369e-05 PAPI_TOT_CYC=1130.64 PAPI_TOT_INS=817.866 garbage=50796820873790 total_execution_walltime=11.464s REALTIME_MAIN_END_PERF_FORMAT=7001680.604396147 [time_cmd_output] time_elapsed_sec=11.58, faults_major=0, faults_minor=76283, mem_maxresident_kb=306536, user_cputime=564.27, sys_cputime=2.30, percent_cpu=4892% timeout=false algorithm=brown_sigouin_abtree_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