__step=000514 __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=data000514.txt __path_data=data/data000514.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=7001000.368092511 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=100096 prefilling_elapsed_millis=1.001s finished prefilling to size 100096 for expected size 100000 keysum=10015461438, performing 1944393 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100096 pref_millis=1102 prefill_elapsed_ms=1128 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=7001001.653914663 REALTIME_END_PERF_FORMAT=7001011.654047071 ############################################################################### ################################## 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 2884 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.051s tree_stats_numInternalsAtDepth=1 1 7 50 372 2884 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 19626 tree_stats_numNodesAtDepth=1 1 7 50 372 2884 19626 tree_stats_numKeysAtDepth=0 0 0 0 0 0 100058 tree_stats_avgDegreeAtDepth=1 7 7.14286 7.44 7.75269 6.80513 5.09824 tree_stats_height=7 tree_stats_numInternals=3315 tree_stats_numLeaves=19626 tree_stats_numNodes=22941 tree_stats_numKeys=100058 tree_stats_avgDegreeInternal=6.92036 tree_stats_avgDegreeLeaves=5.09824 tree_stats_avgDegree=5.36154 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=364272 first_thread_announced_epoch_by_thread=15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 15366 sum_commit_tx_reader_total=158978523 sum_commit_tx_reader_by_thread=0 3340311 3356286 3359319 3327043 3319244 3270187 3301168 3338017 3302888 3313584 3301356 3336816 3277092 3268333 3285360 3357348 3294451 3293371 3323780 3297157 3307068 3301919 3291654 3330408 3263109 3308439 3308704 3303492 3336926 3300439 3265754 3354058 3299630 3326006 3319603 3304264 3317466 3324846 3276553 3350305 3353735 3257895 3322257 3302751 3331411 3268038 3289263 3399419 sum_commit_tx_writer_total=8365154 sum_commit_tx_writer_by_thread=0 175717 176511 177112 175162 174903 171809 173985 175485 173626 174852 173120 175537 172994 171394 173271 176899 173549 172904 175538 173291 173653 173390 174018 175192 171357 174220 174014 173781 175363 173067 171186 176549 173737 175213 174740 173952 174517 174652 172427 176281 176103 171147 175118 174232 175115 172044 173437 178990 sum_abort_tx_total=2517 sum_abort_tx_by_thread=0 53 45 44 51 60 48 51 68 50 61 44 48 47 44 50 61 46 52 47 45 55 55 55 43 52 54 51 59 58 64 51 61 45 57 60 47 57 41 34 62 44 56 51 66 67 57 44 56 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.3:369 31.6:3727 37.9:12276 44.2:20270 50.5:9585 56.8:1690 63.1:58 69.4:9 75.7:9 82:6 [ 19.00, 25.30]: 369 ( 25.30, 31.60]: 3727 ( 31.60, 37.90]: 12276 ( 37.90, 44.20]: 20270 ( 44.20, 50.50]: 9585 ( 50.50, 56.80]: 1690 ( 56.80, 63.10]: 58 ( 63.10, 69.40]: 9 ( 69.40, 75.70]: 9 ( 75.70, 82.00]: 6 linear_histogram_of_none_tx_wrset_size_full_data=19.5:47409 37:16 54.5:282 72:181 89.5:97 124.5:11 142:3 [ 2.00, 19.50]: 47409 ( 19.50, 37.00]: 16 ( 37.00, 54.50]: 282 ( 54.50, 72.00]: 181 ( 72.00, 89.50]: 97 ( 107.00, 124.50]: 11 ( 124.50, 142.00]: 3 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=176474 175151 175377 176341 174023 171522 173254 175168 174380 177208 173174 170869 176151 173049 173654 176939 173326 171763 175235 175440 173218 175005 173840 171521 178514 175187 174131 174778 174141 171437 174515 174448 175713 173711 172694 173313 176305 174197 173517 174931 174652 172895 173378 174429 173677 175034 175383 171980 sum_num_inserts_total=8365042 sum_num_deletes_by_thread=176353 174946 175617 175880 174001 171986 173527 175605 174585 176633 173380 171688 177871 172981 173153 176152 173929 171651 175953 175584 173507 174790 172813 171594 178962 174486 173361 175344 174172 171711 174629 173318 176008 173809 171816 173386 176785 173388 173509 174720 174358 172712 173566 173388 173120 175972 174801 172065 sum_num_deletes_total=8363565 sum_num_searches_by_thread=3177011 3151122 3148324 3163807 3128959 3096219 3129733 3161580 3145182 3182590 3123894 3086485 3180225 3120245 3121193 3177516 3128054 3098582 3155338 3162478 3115975 3152410 3127823 3093825 3220933 3144670 3125875 3155478 3134346 3091318 3139292 3129507 3174865 3133201 3104470 3111932 3179707 3130631 3118646 3142332 3150488 3104479 3128209 3134901 3126709 3161283 3147191 3096037 sum_num_searches_total=150615070 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3529838 3501219 3499318 3516028 3476983 3439727 3476514 3512353 3494147 3536431 3470448 3429042 3534247 3466275 3468000 3530607 3475309 3441996 3506526 3513502 3462700 3502205 3474476 3436940 3578409 3494343 3473367 3505600 3482659 3434466 3488436 3477273 3526586 3480721 3448980 3458631 3532797 3478216 3465672 3491983 3499498 3450086 3475153 3482718 3473506 3512289 3497375 3440082 average_num_operations_total=3486326 stdev_num_operations_total=4613 sum_num_operations_total=167343677 min_num_operations_total=3429042 max_num_operations_total=3578409 first_time_thread_terminate_by_thread=10000133 10000132 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 min_time_thread_terminate_total=10000132 max_time_thread_terminate_total=10000133 first_time_thread_start_by_thread=3 4 3 3 3 4 3 3 3 3 4 3 3 3 3 3 3 4 3 3 3 3 3 3 3 4 3 3 3 4 3 3 3 3 4 3 3 3 3 3 3 4 3 3 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=10008049552 threads_final_size=100058 final_keysum=10008049552 final_size=100058 validate_result=success Validation OK. Structural validation OK. total_find=150615070 total_rq=0 total_inserts=8365042 total_deletes=8363565 total_updates=16728607 total_queries=150615070 total_ops=167343677 find_throughput=15061507 rq_throughput=0 update_throughput=1672860 query_throughput=15061507 total_throughput=16734367 total find : 150615070 total rq : 0 total inserts : 8365042 total deletes : 8363565 total updates : 16728607 total queries : 150615070 total ops : 167343677 find throughput : 15061507 rq throughput : 0 update throughput : 1672860 query throughput : 15061507 total throughput : 16734367 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=11.9593 PAPI_L3_TCM=0.00241239 PAPI_TOT_CYC=6328.43 PAPI_TOT_INS=4835.81 garbage=7536212598225 total_execution_walltime=11.597s REALTIME_MAIN_END_PERF_FORMAT=7001011.965955502 noREC system shutdown: [time_cmd_output] time_elapsed_sec=11.73, faults_major=0, faults_minor=105142, mem_maxresident_kb=422080, user_cputime=529.89, sys_cputime=37.41, percent_cpu=4833% 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=2