__step=000329 __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=data000329.txt __path_data=data/data000329.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6998781.989450969 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.008s prefilling_size=605011 prefilling_elapsed_millis=1.001s prefilling_size=660173 prefilling_elapsed_millis=1.101s prefilling_size=712391 prefilling_elapsed_millis=1.201s prefilling_size=763767 prefilling_elapsed_millis=1.301s prefilling_size=810181 prefilling_elapsed_millis=1.402s prefilling_size=851321 prefilling_elapsed_millis=1.502s prefilling_size=886939 prefilling_elapsed_millis=1.602s prefilling_size=913006 prefilling_elapsed_millis=1.702s prefilling_size=933943 prefilling_elapsed_millis=1.802s prefilling_size=951825 prefilling_elapsed_millis=1.903s prefilling_size=965796 prefilling_elapsed_millis=2.003s prefilling_size=971989 prefilling_elapsed_millis=2.103s prefilling_size=976285 prefilling_elapsed_millis=2.203s prefilling_size=980069 prefilling_elapsed_millis=2.303s finished prefilling to size 980074 for expected size 1000000 keysum=980178080667, performing 7859993 updates; total_prefilling_elapsed_ms=2404 ms) pref_size=980074 pref_millis=2404 prefill_elapsed_ms=2432 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6998784.641789336 REALTIME_END_PERF_FORMAT=6998794.641966427 ############################################################################### ################################## TIME IS UP ################################# ############################################################################### joining threads... ############################################################################### ################################# END RUNNING ################################# ############################################################################### 10.1s PRODUCING OUTPUT computing tree_stats in PARALLEL... bounded depth BFS to partition into subtrees for parallel computation (192 threads)... partitioned into 3361 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.061s tree_stats_numInternalsAtDepth=1 1 8 61 447 3361 26084 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 196291 tree_stats_numNodesAtDepth=1 1 8 61 447 3361 26084 196291 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 1000079 tree_stats_avgDegreeAtDepth=1 8 7.625 7.32787 7.51902 7.76079 7.52534 5.09488 tree_stats_height=8 tree_stats_numInternals=29963 tree_stats_numLeaves=196291 tree_stats_numNodes=226254 tree_stats_numKeys=1000079 tree_stats_avgDegreeInternal=7.55111 tree_stats_avgDegreeLeaves=5.09488 tree_stats_avgDegree=5.42016 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=834912 first_thread_announced_epoch_by_thread=35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 35628 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=388945 390564 393463 393726 391497 388096 389286 391592 395030 395285 390122 389101 389021 392819 393160 395405 393181 391392 387967 391494 393467 395300 393576 388854 390325 390718 394587 394385 392595 389037 389448 391021 394246 395746 393001 392717 390843 392831 393497 394294 393827 390010 387710 390938 394029 395159 390737 389394 sum_num_inserts_total=18813438 sum_num_deletes_by_thread=387779 389975 392787 393776 392149 386800 388986 391840 395740 396152 391569 390167 390379 393574 393768 395029 394157 390433 388868 391624 392442 395617 392646 388630 390286 391543 394490 393812 393094 388805 388778 390887 395157 395729 391989 391384 391055 393215 392968 395615 392248 391551 386260 390945 393463 394113 391285 388869 sum_num_deletes_total=18812428 sum_num_searches_by_thread=6985470 7022042 7078641 7077585 7039232 6973088 6989317 7057792 7101313 7122442 7038838 7021081 7006711 7077647 7088342 7107857 7076458 7042354 7011865 7055096 7088293 7120405 7063560 7000491 7003264 7037232 7103236 7082955 7076576 7009900 7008532 7034905 7100797 7134280 7072872 7043281 7025595 7066103 7087803 7105374 7080179 7037315 6984580 7038684 7090185 7120842 7050861 7005276 sum_num_searches_total=338646547 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=7762194 7802581 7864891 7865087 7822878 7747984 7767589 7841224 7892083 7913879 7820529 7800349 7786111 7864040 7875270 7898291 7863796 7824179 7788700 7838214 7874202 7911322 7849782 7777975 7783875 7819493 7892313 7871152 7862265 7787742 7786758 7816813 7890200 7925755 7857862 7827382 7807493 7852149 7874268 7895283 7866254 7818876 7758550 7820567 7877677 7910114 7832883 7783539 average_num_operations_total=7839008 stdev_num_operations_total=6793 sum_num_operations_total=376272413 min_num_operations_total=7747984 max_num_operations_total=7925755 first_time_thread_terminate_by_thread=10000187 10000186 10000186 10000187 10000187 10000186 10000186 10000186 10000187 10000187 10000186 10000186 10000187 10000187 10000186 10000186 10000210 10000187 10000186 10000187 10000187 10000186 10000186 10000186 10000186 10000186 10000186 10000187 10000186 10000186 10000186 10000186 10000187 10000186 10000186 10000186 10000187 10000187 10000186 10000186 10000187 10000186 10000186 10000187 10000187 10000186 10000186 10000186 min_time_thread_terminate_total=10000186 max_time_thread_terminate_total=10000210 first_time_thread_start_by_thread=5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 6 5 5 5 5 5 5 5 min_time_thread_start_total=5 max_time_thread_start_total=6 sum_duration_all_ops_total= threads_final_keysum=1000384348011 threads_final_size=1000079 final_keysum=1000384348011 final_size=1000079 validate_result=success Validation OK. Structural validation OK. total_find=338646547 total_rq=0 total_inserts=18813438 total_deletes=18812428 total_updates=37625866 total_queries=338646547 total_ops=376272413 find_throughput=33864654 rq_throughput=0 update_throughput=3762586 query_throughput=33864654 total_throughput=37627241 total find : 338646547 total rq : 0 total inserts : 18813438 total deletes : 18812428 total updates : 37625866 total queries : 338646547 total ops : 376272413 find throughput : 33864654 rq throughput : 0 update throughput : 3762586 query throughput : 33864654 total throughput : 37627241 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=11.1007 PAPI_L3_TCM=1.49751 PAPI_TOT_CYC=3537.87 PAPI_TOT_INS=2306.17 garbage=169192446919943 total_execution_walltime=12.895s REALTIME_MAIN_END_PERF_FORMAT=6998794.901696818 HyTM1 system shutdown: HTM_ATTEMPT_THRESH=40 [fast htm] total fast htm commit : 383717566 [slow htm] [fallback] total fallback commit : 414841 seconds global lock is held : 5.76109 [time_cmd_output] time_elapsed_sec=13.08, faults_major=0, faults_minor=275135, mem_maxresident_kb=1022228, user_cputime=627.88, sys_cputime=3.18, percent_cpu=4824% 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=1