__step=000258 __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_int_avl_tm_auto.debra.norec -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000258.txt __path_data=data/data000258.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6997662.93801483 binary=./brown_sigouin_int_avl_tm_auto.debra.norec parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_sigouin_int_avl_tm_auto 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=2000000 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=48 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 noREC system ready Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.008s prefilling_size=615277 prefilling_elapsed_millis=1.001s prefilling_size=650241 prefilling_elapsed_millis=1.101s prefilling_size=682370 prefilling_elapsed_millis=1.201s prefilling_size=711485 prefilling_elapsed_millis=1.301s prefilling_size=737352 prefilling_elapsed_millis=1.402s prefilling_size=761049 prefilling_elapsed_millis=1.502s prefilling_size=782807 prefilling_elapsed_millis=1.602s prefilling_size=802526 prefilling_elapsed_millis=1.702s prefilling_size=820050 prefilling_elapsed_millis=1.802s prefilling_size=835831 prefilling_elapsed_millis=1.903s prefilling_size=850836 prefilling_elapsed_millis=2.003s prefilling_size=864129 prefilling_elapsed_millis=2.103s prefilling_size=876179 prefilling_elapsed_millis=2.203s prefilling_size=886769 prefilling_elapsed_millis=2.303s prefilling_size=897257 prefilling_elapsed_millis=2.404s prefilling_size=906570 prefilling_elapsed_millis=2.504s prefilling_size=914665 prefilling_elapsed_millis=2.604s prefilling_size=922451 prefilling_elapsed_millis=2.704s prefilling_size=929204 prefilling_elapsed_millis=2.804s prefilling_size=935570 prefilling_elapsed_millis=2.905s prefilling_size=941492 prefilling_elapsed_millis=3.005s prefilling_size=946958 prefilling_elapsed_millis=3.105s prefilling_size=951559 prefilling_elapsed_millis=3.205s prefilling_size=956002 prefilling_elapsed_millis=3.305s prefilling_size=959803 prefilling_elapsed_millis=3.406s prefilling_size=963486 prefilling_elapsed_millis=3.506s prefilling_size=966883 prefilling_elapsed_millis=3.606s prefilling_size=969439 prefilling_elapsed_millis=3.706s prefilling_size=971645 prefilling_elapsed_millis=3.806s prefilling_size=974197 prefilling_elapsed_millis=3.907s prefilling_size=976074 prefilling_elapsed_millis=4.007s prefilling_size=978680 prefilling_elapsed_millis=4.107s prefilling_size=980612 prefilling_elapsed_millis=4.207s finished prefilling to size 980608 for expected size 1000000 keysum=980643545705, performing 8309924 updates; total_prefilling_elapsed_ms=4308 ms) pref_size=980608 pref_millis=4308 prefill_elapsed_ms=4335 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6997666.603013248 REALTIME_END_PERF_FORMAT=6997676.603151925 ############################################################################### ################################## 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.038s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32760 64779 117072 157453 121779 42699 5443 142 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 7 678 10758 62435 147704 143732 53825 6874 172 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32767 65457 127830 219888 269483 186431 59268 7016 172 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32767 65457 127830 219888 269483 186431 59268 7016 172 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99994 1.99786 1.96324 1.80432 1.50949 1.23991 1.08887 1.02654 1.00428 1 tree_stats_height=24 tree_stats_numInternals=574894 tree_stats_numLeaves=426185 tree_stats_numNodes=1001079 tree_stats_numKeys=1001079 tree_stats_avgDegreeInternal=1.74133 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42573 tree_stats_avgKeyDepth=18.3279 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 [2^00, 2^01]: 0 sum_limbo_reclamation_event_size_total= count_limbo_reclamation_event_size_by_thread= count_limbo_reclamation_event_size_total= sum_limbo_reclamation_event_count_total=281664 first_thread_announced_epoch_by_thread=12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 sum_commit_tx_reader_total=122972698 sum_commit_tx_reader_by_thread=0 2553982 2543926 2546186 2584978 2572546 2546847 2554909 2536463 2507027 2560183 2537474 2556523 2539753 2568608 2606997 2569696 2582128 2545206 2580116 2573094 2560309 2547082 2584904 2559021 2556785 2570314 2558500 2579660 2574036 2542315 2542558 2568677 2563401 2558901 2534005 2585587 2567615 2543621 2546047 2583871 2550195 2560078 2561955 2610425 2553586 2580234 2567856 2594518 sum_commit_tx_writer_total=6469087 sum_commit_tx_writer_by_thread=0 134133 133955 134186 135599 135072 133862 134901 134233 131565 135082 133167 134288 133197 135214 137260 135378 135666 133764 135772 135513 135392 133351 136055 134059 134663 135214 134896 136237 135574 133570 133616 134983 135301 134450 133438 135871 135083 133025 134002 135932 134626 134989 134432 137305 134054 135843 134776 136543 sum_abort_tx_total=1556 sum_abort_tx_by_thread=0 37 30 28 38 35 32 35 40 37 35 38 40 30 25 39 38 39 30 32 24 28 34 33 33 34 27 33 29 29 32 26 29 32 31 34 38 27 27 31 47 32 30 33 42 18 34 25 26 max_ht_capacity_by_thread=0 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 linear_histogram_of_none_tx_rdset_size_full_data=19.2:4 26.4:66 33.6:656 40.8:15643 48:29202 55.2:1808 62.4:492 69.6:102 76.8:21 84:5 [ 12.00, 19.20]: 4 ( 19.20, 26.40]: 66 ( 26.40, 33.60]: 656 ( 33.60, 40.80]: 15643 ( 40.80, 48.00]: 29202 ( 48.00, 55.20]: 1808 ( 55.20, 62.40]: 492 ( 62.40, 69.60]: 102 ( 69.60, 76.80]: 21 ( 76.80, 84.00]: 5 linear_histogram_of_none_tx_wrset_size_full_data=3.7:14265 6.4:3388 9.1:12247 11.8:4844 14.5:9036 17.2:3113 19.9:408 22.6:600 25.3:93 28:5 [ 1.00, 3.70]: 14265 ( 3.70, 6.40]: 3388 ( 6.40, 9.10]: 12247 ( 9.10, 11.80]: 4844 ( 11.80, 14.50]: 9036 ( 14.50, 17.20]: 3113 ( 17.20, 19.90]: 408 ( 19.90, 22.60]: 600 ( 22.60, 25.30]: 93 ( 25.30, 28.00]: 5 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= sum_abtree_erase_repeat_total= sum_abtree_search_repeat_total= sum_abtree_search_complete_total= sum_abtree_fix_weight_viol_attempt_total= sum_abtree_fix_degree_viol_attempt_total= sum_abtree_rebalancing_kcas_attempt_total= sum_abtree_update_kcas_attempt_total= sum_abtree_internal_complex_search_while_iters_total= sum_abtree_internal_basic_search_while_iters_total= sum_kcas_desc_new_total= sum_num_inserts_by_thread=133319 133806 133101 135896 133296 137155 133597 134435 135751 135944 134235 134320 134242 135553 135955 134537 135379 134950 133831 134479 133837 135027 136439 134512 131552 133439 134992 135533 134006 134546 134875 135022 135942 136119 134765 134689 134525 135768 137486 136292 135557 134877 133605 134375 134053 137728 134739 133313 sum_num_inserts_total=6471394 sum_num_deletes_by_thread=133546 133830 133660 135004 133663 136331 133645 133432 134825 136056 133946 133832 133380 135275 134959 135024 135997 135468 133626 135277 134486 134858 135803 134356 132012 133183 133858 134895 134981 134838 135210 135037 135474 135985 134562 134815 134202 135124 137199 135530 135476 134850 133768 134794 134059 137037 135082 133340 sum_num_deletes_total=6465590 sum_num_searches_by_thread=2409309 2408249 2400682 2438710 2409687 2457575 2410639 2411103 2433246 2448577 2412191 2419963 2413087 2445060 2436704 2423835 2449583 2435110 2412976 2433904 2411726 2432813 2449216 2424483 2375028 2404019 2422598 2434646 2424093 2425881 2428617 2425642 2446378 2447699 2427060 2425563 2416094 2437715 2469572 2444075 2445044 2432905 2403323 2421642 2419528 2472965 2419989 2406297 sum_num_searches_total=116504801 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=2676174 2675885 2667443 2709610 2676646 2731061 2677881 2678970 2703822 2720577 2680372 2688115 2680709 2715888 2707618 2693396 2720959 2705528 2680433 2703660 2680049 2702698 2721458 2693351 2638592 2670641 2691448 2705074 2693080 2695265 2698702 2695701 2717794 2719803 2696387 2695067 2684821 2708607 2744257 2715897 2716077 2702632 2670696 2690811 2687640 2747730 2689810 2672950 average_num_operations_total=2696703 stdev_num_operations_total=3027 sum_num_operations_total=129441785 min_num_operations_total=2638592 max_num_operations_total=2747730 first_time_thread_terminate_by_thread=10000138 10000138 10000139 10000138 10000138 10000138 10000138 10000138 10000139 10000138 10000139 10000138 10000139 10000138 10000139 10000139 10000138 10000139 10000139 10000138 10000139 10000139 10000138 10000139 10000138 10000139 10000139 10000138 10000138 10000138 10000139 10000139 10000139 10000138 10000139 10000138 10000138 10000138 10000138 10000139 10000138 10000139 10000139 10000139 10000138 10000138 10000139 10000138 min_time_thread_terminate_total=10000138 max_time_thread_terminate_total=10000139 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=1000633070830 threads_final_size=1001079 final_keysum=1000633070830 final_size=1001079 validate_result=success Validation OK. Structural validation OK. total_find=116504801 total_rq=0 total_inserts=6471394 total_deletes=6465590 total_updates=12936984 total_queries=116504801 total_ops=129441785 find_throughput=11650480 rq_throughput=0 update_throughput=1293698 query_throughput=11650480 total_throughput=12944178 total find : 116504801 total rq : 0 total inserts : 6471394 total deletes : 6465590 total updates : 12936984 total queries : 116504801 total ops : 129441785 find throughput : 11650480 rq throughput : 0 update throughput : 1293698 query throughput : 11650480 total throughput : 12944178 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=17.1286 PAPI_L3_TCM=1.58813 PAPI_TOT_CYC=8705.6 PAPI_TOT_INS=5867.34 garbage=56860957593996 total_execution_walltime=14.813s REALTIME_MAIN_END_PERF_FORMAT=6997676.908283252 noREC system shutdown: [time_cmd_output] time_elapsed_sec=14.95, faults_major=0, faults_minor=145170, mem_maxresident_kb=582460, user_cputime=691.49, sys_cputime=29.73, percent_cpu=4823% timeout=false algorithm=brown_sigouin_int_avl_tm_auto.debra.norec thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=2