__step=000434 __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_int_bst_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=data000434.txt __path_data=data/data000434.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=7000026.36521870 binary=./brown_int_bst_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_int_bst_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=32 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=99832 prefilling_elapsed_millis=1.001s finished prefilling to size 99816 for expected size 100000 keysum=9963676478, performing 1874146 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=99816 pref_millis=1102 prefill_elapsed_ms=1124 4NodeIxPvL9tx_safety0EE_object_size=32 4NodeIxPvL9tx_safety0EE_allocated_count=0 4NodeIxPvL9tx_safety0EE_allocated_size=0MB 4NodeIxPvL9tx_safety0EE_get_from_pool=0 4NodeIxPvL9tx_safety0EE_deallocated=0 4NodeIxPvL9tx_safety0EE_limbo_count=0 4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 global_epoch_counter=88 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=7000027.334004205 REALTIME_END_PERF_FORMAT=7000037.334157852 ############################################################################### ################################## 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 794 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.031s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 62 118 229 419 748 1298 2080 3076 4211 5273 6175 6641 6668 6197 5613 4699 3777 2845 2013 1351 859 471 230 118 59 33 22 16 13 6 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 2 3 4 17 46 101 306 583 1072 1704 2304 3049 3465 3831 3578 3454 2947 2453 1959 1379 933 657 367 170 90 38 21 16 10 11 7 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 121 233 436 794 1399 2386 3659 5283 6977 8479 9690 10133 10028 9191 8153 6724 5298 3972 2730 1792 1128 597 288 149 71 43 32 23 17 8 2 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 121 233 436 794 1399 2386 3659 5283 6977 8479 9690 10133 10028 9191 8153 6724 5298 3972 2730 1792 1128 597 288 149 71 43 32 23 17 8 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.92188 1.95041 1.88841 1.86009 1.8199 1.7777 1.66178 1.60317 1.52357 1.45951 1.41455 1.36037 1.33159 1.29856 1.27636 1.24837 1.2262 1.21272 1.18051 1.16154 1.15011 1.1117 1.09715 1.10764 1.08054 1.14085 1.23256 1.21875 1.17391 1.11765 1.125 1 tree_stats_height=38 tree_stats_numInternals=65384 tree_stats_numLeaves=34579 tree_stats_numNodes=99963 tree_stats_numKeys=99963 tree_stats_avgDegreeInternal=1.52886 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34592 tree_stats_avgKeyDepth=18.6078 4NodeIxPvL9tx_safety0EE_object_size=32 4NodeIxPvL9tx_safety0EE_allocated_count=0 4NodeIxPvL9tx_safety0EE_allocated_size=0MB 4NodeIxPvL9tx_safety0EE_get_from_pool=0 4NodeIxPvL9tx_safety0EE_deallocated=0 4NodeIxPvL9tx_safety0EE_limbo_count=0 4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 global_epoch_counter=6990 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:41 2:990 4:35536 8:255798 16:38832 [2^00, 2^01]: 41 (2^01, 2^02]: 990 (2^02, 2^03]: 35536 (2^03, 2^04]: 255798 (2^04, 2^05]: 38832 sum_limbo_reclamation_event_size_total=3802385 count_limbo_reclamation_event_size_by_thread=6900 6900 6900 6900 6900 6900 6900 6900 6899 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6899 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6900 6899 6900 6900 6900 6900 6900 count_limbo_reclamation_event_size_total=331197 sum_limbo_reclamation_event_count_total=331296 first_thread_announced_epoch_by_thread=13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 13980 sum_commit_tx_reader_total=144662513 sum_commit_tx_reader_by_thread=0 2972230 3002939 2990141 3055943 3025791 3003702 2991018 3010267 3044609 3004973 3039217 3031354 3032343 3002456 3018683 3007092 2982336 3027468 3018193 3012496 3039202 3013290 3050687 3016691 3003067 3025461 2962150 3021949 2993351 2997361 3028949 2988569 3053858 3076060 3010604 2964486 2986366 2995912 3025026 2980711 3003055 3037439 3007021 3041293 3003080 3005563 3009348 3048713 sum_commit_tx_writer_total=7608227 sum_commit_tx_writer_by_thread=0 155961 158006 157141 160154 158467 157969 158043 157630 160161 158310 159439 159535 158943 158884 158630 158012 157019 158814 158367 158302 159532 158326 160774 158911 158317 158983 155869 158904 157874 158122 159952 157157 160642 161630 158614 156264 156495 158170 158422 156358 158009 159935 158179 159807 158137 158064 158700 160293 sum_abort_tx_total=13689 sum_abort_tx_by_thread=0 269 279 276 271 270 285 265 302 306 291 291 286 296 293 281 280 284 287 267 294 263 319 270 278 280 287 256 312 286 286 297 303 275 293 282 275 307 278 316 283 282 289 286 255 296 315 280 267 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=13.2:10 20.4:130 27.6:843 34.8:6862 42:12495 49.2:17484 56.4:7998 63.6:1849 70.8:312 78:14 [ 6.00, 13.20]: 10 ( 13.20, 20.40]: 130 ( 20.40, 27.60]: 843 ( 27.60, 34.80]: 6862 ( 34.80, 42.00]: 12495 ( 42.00, 49.20]: 17484 ( 49.20, 56.40]: 7998 ( 56.40, 63.60]: 1849 ( 63.60, 70.80]: 312 ( 70.80, 78.00]: 14 linear_histogram_of_none_tx_wrset_size_full_data=1.4:15612 3.4:8192 [ 1.00, 1.40]: 15612 ( 3.00, 3.40]: 8192 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=159147 157388 160530 158110 157827 158534 155445 158662 157986 160801 157714 159402 158478 160085 159104 158667 158749 158305 157008 159363 157506 157786 160273 156773 158635 158230 157911 158454 161284 157788 156596 158128 158991 162064 159832 158609 159655 158070 158816 161002 160435 158009 158583 158502 156480 158648 159027 156389 sum_num_inserts_total=7613781 sum_num_deletes_by_thread=159704 157017 159808 157971 157352 158189 156501 159261 158163 161066 159286 160365 157858 160852 159037 157294 158123 159148 157057 159605 157281 158167 159109 156571 159113 157758 158245 158114 159440 158260 156144 157662 158609 161250 159654 158376 159783 158310 159286 160440 159594 157637 158076 157671 157260 158816 159014 156264 sum_num_deletes_total=7609561 sum_num_searches_by_thread=2872038 2831321 2884432 2847202 2833882 2844494 2806073 2866521 2849051 2892633 2844384 2869134 2844609 2895160 2866117 2845710 2859688 2853345 2825290 2872318 2832495 2839530 2879352 2829517 2859565 2849116 2845184 2852650 2888282 2851849 2808010 2838292 2868682 2914376 2879170 2854631 2881662 2847247 2857500 2890019 2877345 2835579 2851389 2844891 2823329 2863389 2865407 2815538 sum_num_searches_total=137047398 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3190889 3145726 3204770 3163283 3149061 3161217 3118019 3184444 3165200 3214500 3161384 3188901 3160945 3216097 3184258 3161671 3176560 3170798 3139355 3191286 3147282 3155483 3198734 3142861 3177313 3165104 3161340 3169218 3209006 3167897 3120750 3154082 3186282 3237690 3198656 3171616 3201100 3163627 3175602 3211461 3197374 3151225 3168048 3161064 3137069 3180853 3183448 3128191 average_num_operations_total=3172307 stdev_num_operations_total=3757 sum_num_operations_total=152270740 min_num_operations_total=3118019 max_num_operations_total=3237690 first_time_thread_terminate_by_thread=10000151 10000151 10000151 10000152 10000152 10000152 10000151 10000152 10000152 10000151 10000152 10000151 10000151 10000152 10000153 10000153 10000152 10000151 10000152 10000151 10000152 10000151 10000153 10000153 10000152 10000151 10000152 10000153 10000151 10000153 10000153 10000152 10000152 10000151 10000152 10000151 10000152 10000151 10000153 10000153 10000152 10000153 10000152 10000151 10000152 10000153 10000153 10000152 min_time_thread_terminate_total=10000151 max_time_thread_terminate_total=10000153 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=9970135699 threads_final_size=99963 final_keysum=9970135699 final_size=99963 validate_result=success Validation OK. Structural validation OK. total_find=137047398 total_rq=0 total_inserts=7613781 total_deletes=7609561 total_updates=15223342 total_queries=137047398 total_ops=152270740 find_throughput=13704739 rq_throughput=0 update_throughput=1522334 query_throughput=13704739 total_throughput=15227074 total find : 137047398 total rq : 0 total inserts : 7613781 total deletes : 7609561 total updates : 15223342 total queries : 137047398 total ops : 152270740 find throughput : 13704739 rq throughput : 0 update throughput : 1522334 query throughput : 13704739 total throughput : 15227074 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=9.26641 PAPI_L3_TCM=0.000689128 PAPI_TOT_CYC=7151.09 PAPI_TOT_INS=5410.77 garbage=6849989155375 total_execution_walltime=11.597s REALTIME_MAIN_END_PERF_FORMAT=7000037.635792178 noREC system shutdown: [time_cmd_output] time_elapsed_sec=11.73, faults_major=0, faults_minor=76808, mem_maxresident_kb=309180, user_cputime=530.81, sys_cputime=34.05, percent_cpu=4813% timeout=false algorithm=brown_int_bst_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