__step=000330 __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_abtree_tm_auto.debra.hytm1 -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=data000330.txt __path_data=data/data000330.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6998795.122371688 binary=./brown_abtree_tm_auto.debra.hytm1 parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_abtree_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=200 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 HyTM1 system ready Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.005s prefilling_size=603078 prefilling_elapsed_millis=1.001s prefilling_size=657956 prefilling_elapsed_millis=1.101s prefilling_size=711080 prefilling_elapsed_millis=1.201s prefilling_size=760912 prefilling_elapsed_millis=1.301s prefilling_size=807516 prefilling_elapsed_millis=1.402s prefilling_size=848942 prefilling_elapsed_millis=1.502s prefilling_size=885868 prefilling_elapsed_millis=1.602s prefilling_size=912738 prefilling_elapsed_millis=1.702s prefilling_size=934092 prefilling_elapsed_millis=1.802s prefilling_size=951894 prefilling_elapsed_millis=1.903s prefilling_size=966175 prefilling_elapsed_millis=2.003s prefilling_size=971329 prefilling_elapsed_millis=2.103s prefilling_size=975415 prefilling_elapsed_millis=2.203s prefilling_size=979953 prefilling_elapsed_millis=2.303s prefilling_size=987702 prefilling_elapsed_millis=2.404s finished prefilling to size 987737 for expected size 1000000 keysum=987765968837, performing 8893078 updates; total_prefilling_elapsed_ms=2505 ms) pref_size=987737 pref_millis=2505 prefill_elapsed_ms=2529 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6998797.802012994 REALTIME_END_PERF_FORMAT=6998807.802178447 ############################################################################### ################################## 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 3376 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.06s tree_stats_numInternalsAtDepth=1 1 7 57 452 3376 26240 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 196205 tree_stats_numNodesAtDepth=1 1 7 57 452 3376 26240 196205 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 999882 tree_stats_avgDegreeAtDepth=1 7 8.14286 7.92982 7.46903 7.77251 7.47732 5.09611 tree_stats_height=8 tree_stats_numInternals=30134 tree_stats_numLeaves=196205 tree_stats_numNodes=226339 tree_stats_numKeys=999882 tree_stats_avgDegreeInternal=7.51108 tree_stats_avgDegreeLeaves=5.09611 tree_stats_avgDegree=5.41763 tree_stats_avgKeyDepth=7 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=832896 first_thread_announced_epoch_by_thread=35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 35650 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= 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=387917 390676 392755 390873 388619 386157 388656 390494 393712 394830 390623 390095 388079 392548 390855 394656 391971 391109 387629 390432 389990 395894 390326 388634 386243 389381 390560 393238 389987 388208 388201 390152 394718 395244 391509 388549 387699 392393 391616 394121 392666 389513 385062 388274 391042 394460 388846 387511 sum_num_inserts_total=18746723 sum_num_deletes_by_thread=386996 389987 392273 390805 389457 387897 387860 390082 393811 394755 389646 390125 386531 391838 391266 394666 392448 390227 387323 390934 390043 394869 390631 388198 386830 389148 391216 393615 390526 388405 388817 389246 394424 394788 391775 389128 388403 392763 391989 393521 391766 391235 384764 388881 390209 393408 388919 388668 sum_num_deletes_total=18745112 sum_num_searches_by_thread=6974331 7023576 7056697 7053080 6997171 6971682 6975466 7040216 7092253 7110258 7009742 7023075 6969450 7064732 7044523 7102595 7063796 7035012 6967153 7031477 7031668 7095904 7026933 6985031 6957887 7006746 7041686 7089564 7029789 6993091 6997591 7018217 7104615 7097457 7043276 7003824 6979142 7069367 7061252 7085704 7066643 7035873 6926066 6997211 7026765 7098039 7015478 6998870 sum_num_searches_total=337489974 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=7749244 7804239 7841725 7834758 7775247 7745736 7751982 7820792 7879776 7899843 7790011 7803295 7744060 7849118 7826644 7891917 7848215 7816348 7742105 7812843 7811701 7886667 7807890 7761863 7730960 7785275 7823462 7876417 7810302 7769704 7774609 7797615 7893757 7887489 7826560 7781501 7755244 7854523 7844857 7873346 7851075 7816621 7695892 7774366 7808016 7885907 7793243 7775049 average_num_operations_total=7812121 stdev_num_operations_total=7265 sum_num_operations_total=374981809 min_num_operations_total=7695892 max_num_operations_total=7899843 first_time_thread_terminate_by_thread=10000163 10000183 10000164 10000163 10000163 10000163 10000164 10000164 10000164 10000164 10000163 10000163 10000164 10000164 10000164 10000164 10000163 10000164 10000164 10000164 10000163 10000164 10000164 10000163 10000163 10000163 10000164 10000163 10000163 10000163 10000164 10000164 10000164 10000164 10000163 10000163 10000164 10000164 10000164 10000164 10000163 10000164 10000195 10000164 10000163 10000164 10000164 10000164 min_time_thread_terminate_total=10000163 max_time_thread_terminate_total=10000195 first_time_thread_start_by_thread=4 4 4 4 4 4 4 3 4 4 3 4 4 4 4 4 4 4 3 4 4 3 3 4 4 4 4 4 4 4 4 3 4 4 3 4 4 4 4 4 4 4 3 4 4 3 3 4 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=999702285778 threads_final_size=999882 final_keysum=999702285778 final_size=999882 validate_result=success Validation OK. Structural validation OK. total_find=337489974 total_rq=0 total_inserts=18746723 total_deletes=18745112 total_updates=37491835 total_queries=337489974 total_ops=374981809 find_throughput=33748997 rq_throughput=0 update_throughput=3749183 query_throughput=33748997 total_throughput=37498180 total find : 337489974 total rq : 0 total inserts : 18746723 total deletes : 18745112 total updates : 37491835 total queries : 337489974 total ops : 374981809 find throughput : 33748997 rq throughput : 0 update throughput : 3749183 query throughput : 33748997 total throughput : 37498180 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=11.1288 PAPI_L3_TCM=1.51196 PAPI_TOT_CYC=3550.12 PAPI_TOT_INS=2303.09 garbage=165150216990393 total_execution_walltime=12.85s REALTIME_MAIN_END_PERF_FORMAT=6998807.973929543 HyTM1 system shutdown: HTM_ATTEMPT_THRESH=40 [fast htm] total fast htm commit : 383453399 [slow htm] [fallback] total fallback commit : 421489 seconds global lock is held : 5.81135 [time_cmd_output] time_elapsed_sec=13.02, faults_major=0, faults_minor=275455, mem_maxresident_kb=1023260, user_cputime=630.31, sys_cputime=3.20, percent_cpu=4863% timeout=false algorithm=brown_abtree_tm_auto.debra.hytm1 thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=2