__step=000513 __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.norec -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=data000513.txt __path_data=data/data000513.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=7000988.588468648 binary=./brown_abtree_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_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=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=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 noREC system ready Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.008s prefilling_size=99660 prefilling_elapsed_millis=1.001s finished prefilling to size 99648 for expected size 100000 keysum=9971248058, performing 2002194 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=99648 pref_millis=1102 prefill_elapsed_ms=1122 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=7000989.861972275 REALTIME_END_PERF_FORMAT=7000999.862118078 ############################################################################### ################################## 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 2881 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 49 366 2881 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 19551 tree_stats_numNodesAtDepth=1 1 8 49 366 2881 19551 tree_stats_numKeysAtDepth=0 0 0 0 0 0 99923 tree_stats_avgDegreeAtDepth=1 8 6.125 7.46939 7.87158 6.78619 5.11089 tree_stats_height=7 tree_stats_numInternals=3306 tree_stats_numLeaves=19551 tree_stats_numNodes=22857 tree_stats_numKeys=99923 tree_stats_avgDegreeInternal=6.91379 tree_stats_avgDegreeLeaves=5.11089 tree_stats_avgDegree=5.37166 tree_stats_avgKeyDepth=6 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=363360 first_thread_announced_epoch_by_thread=15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 15330 sum_commit_tx_reader_total=158632045 sum_commit_tx_reader_by_thread=0 3277295 3300593 3269201 3312006 3276532 3342189 3304743 3292833 3338401 3325576 3341170 3358383 3329654 3311497 3343941 3320150 3258102 3315137 3263625 3302678 3334886 3286791 3324793 3303548 3280116 3319459 3335971 3301061 3305056 3322605 3340564 3240684 3273046 3260802 3298161 3309364 3272517 3324323 3291817 3254476 3298826 3332621 3275976 3276090 3331439 3263529 3300206 3389612 sum_commit_tx_writer_total=8345029 sum_commit_tx_writer_by_thread=0 171752 173548 172071 174233 172127 175580 173791 173234 175567 174823 176133 176462 175023 174056 176062 174687 171467 173629 171804 173693 175526 173181 175385 173980 172425 174805 176288 173051 174149 175286 175853 170484 172058 171744 173425 174761 172131 174792 172597 171154 172972 175887 172244 172625 174996 171452 173819 178217 sum_abort_tx_total=2642 sum_abort_tx_by_thread=0 50 59 51 67 54 73 51 62 50 49 57 49 58 54 43 58 56 78 60 43 52 54 54 51 47 53 56 50 44 62 60 55 65 53 47 45 72 51 52 51 49 72 52 58 53 54 54 54 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=25.8:310 32.6:5162 39.4:16580 46.2:18615 53:6398 59.8:873 66.6:34 73.4:17 80.2:6 87:4 [ 19.00, 25.80]: 310 ( 25.80, 32.60]: 5162 ( 32.60, 39.40]: 16580 ( 39.40, 46.20]: 18615 ( 46.20, 53.00]: 6398 ( 53.00, 59.80]: 873 ( 59.80, 66.60]: 34 ( 66.60, 73.40]: 17 ( 73.40, 80.20]: 6 ( 80.20, 87.00]: 4 linear_histogram_of_none_tx_wrset_size_full_data=19.5:47343 37:17 54.5:329 72:194 89.5:106 124.5:8 142:1 177:1 [ 2.00, 19.50]: 47343 ( 19.50, 37.00]: 17 ( 37.00, 54.50]: 329 ( 54.50, 72.00]: 194 ( 72.00, 89.50]: 106 ( 107.00, 124.50]: 8 ( 124.50, 142.00]: 1 ( 159.50, 177.00]: 1 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=175730 172315 174126 172155 174625 170756 172283 172883 174343 174210 172868 172299 173941 176055 175617 174633 173375 173646 171495 175529 174557 176153 174724 171667 172811 174096 172489 178214 173458 171035 174727 175866 176097 175579 171519 170585 176817 175115 175378 174180 174974 173073 173778 172047 172115 176520 171933 173350 sum_num_inserts_total=8345741 sum_num_deletes_by_thread=175484 173824 173650 172121 174034 172160 171801 174126 174859 173237 173082 173255 173941 177120 176182 176340 173619 174156 171716 175356 173965 175354 175409 171835 172348 173466 172087 178084 172644 171869 174998 175085 175605 174429 171818 170004 176376 175350 174883 174280 175235 173973 173666 172449 172614 175713 172205 174116 sum_num_deletes_total=8349923 sum_num_searches_by_thread=3159198 3113833 3126249 3104383 3136894 3086653 3097188 3119058 3145635 3128924 3125848 3106987 3140884 3164128 3160460 3166796 3131540 3129726 3089335 3147006 3135603 3162461 3150045 3091927 3103556 3138677 3103644 3211531 3118312 3082726 3149390 3157557 3168301 3156427 3091644 3070579 3181652 3154212 3144003 3130745 3150190 3124540 3126668 3100608 3099919 3164184 3104909 3126675 sum_num_searches_total=150281410 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3510412 3459972 3474025 3448659 3485553 3429569 3441272 3466067 3494837 3476371 3471798 3452541 3488766 3517303 3512259 3517769 3478534 3477528 3432546 3497891 3484125 3513968 3500178 3435429 3448715 3486239 3448220 3567829 3464414 3425630 3499115 3508508 3520003 3506435 3434981 3411168 3534845 3504677 3494264 3479205 3500399 3471586 3474112 3445104 3444648 3516417 3449047 3474141 average_num_operations_total=3478689 stdev_num_operations_total=4756 sum_num_operations_total=166977074 min_num_operations_total=3411168 max_num_operations_total=3567829 first_time_thread_terminate_by_thread=10000145 10000144 10000145 10000145 10000145 10000145 10000144 10000145 10000145 10000145 10000145 10000144 10000145 10000145 10000145 10000145 10000144 10000144 10000145 10000145 10000145 10000145 10000145 10000145 10000145 10000144 10000145 10000145 10000145 10000145 10000144 10000145 10000145 10000145 10000145 10000144 10000145 10000145 10000145 10000145 10000144 10000144 10000145 10000145 10000145 10000145 10000144 10000145 min_time_thread_terminate_total=10000144 max_time_thread_terminate_total=10000145 first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 min_time_thread_start_total=4 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=9994246978 threads_final_size=99923 final_keysum=9994246978 final_size=99923 validate_result=success Validation OK. Structural validation OK. total_find=150281410 total_rq=0 total_inserts=8345741 total_deletes=8349923 total_updates=16695664 total_queries=150281410 total_ops=166977074 find_throughput=15028141 rq_throughput=0 update_throughput=1669566 query_throughput=15028141 total_throughput=16697707 total find : 150281410 total rq : 0 total inserts : 8345741 total deletes : 8349923 total updates : 16695664 total queries : 150281410 total ops : 166977074 find throughput : 15028141 rq throughput : 0 update throughput : 1669566 query throughput : 15028141 total throughput : 16697707 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=11.7812 PAPI_L3_TCM=0.00242432 PAPI_TOT_CYC=6359.26 PAPI_TOT_INS=4855.15 garbage=7512429394652 total_execution_walltime=11.579s REALTIME_MAIN_END_PERF_FORMAT=7001000.168169516 noREC system shutdown: [time_cmd_output] time_elapsed_sec=11.69, faults_major=0, faults_minor=104960, mem_maxresident_kb=421112, user_cputime=527.47, sys_cputime=37.03, percent_cpu=4826% timeout=false algorithm=brown_abtree_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=1