__step=000433 __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=data000433.txt __path_data=data/data000433.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=7000014.224895049 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.007s prefilling_size=100344 prefilling_elapsed_millis=1.001s finished prefilling to size 100348 for expected size 100000 keysum=10030760758, performing 1921743 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100348 pref_millis=1102 prefill_elapsed_ms=1121 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=92 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=7000015.539035583 REALTIME_END_PERF_FORMAT=7000025.539188748 ############################################################################### ################################## 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 883 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.035s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 126 240 463 833 1419 2202 3144 4122 5045 5835 6400 6615 6381 5789 4927 3920 3018 2066 1321 773 390 160 62 21 6 2 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 2 6 4 50 139 355 706 1189 1742 2273 2789 3270 3670 3625 3522 3123 2544 2078 1450 960 598 317 137 51 17 5 1 1 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 246 467 883 1558 2557 3850 5311 6787 8108 9189 9885 10051 9414 8449 7043 5562 4144 2771 1733 988 477 199 72 23 7 2 1 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 246 467 883 1558 2557 3850 5311 6787 8108 9189 9885 10051 9414 8449 7043 5562 4144 2771 1733 988 477 199 72 23 7 2 1 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.9375 1.92276 1.89936 1.82106 1.73042 1.64451 1.56286 1.50179 1.4513 1.41367 1.37926 1.3476 1.30176 1.28256 1.25044 1.23314 1.20245 1.17013 1.14868 1.12406 1.08806 1.08176 1.05025 1.02778 1.04348 1 1 1 tree_stats_height=35 tree_stats_numInternals=65408 tree_stats_numLeaves=34624 tree_stats_numNodes=100032 tree_stats_numKeys=100032 tree_stats_avgDegreeInternal=1.52935 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34613 tree_stats_avgKeyDepth=18.5619 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=7076 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:46 2:1097 4:37849 8:258663 16:37478 [2^00, 2^01]: 46 (2^01, 2^02]: 1097 (2^02, 2^03]: 37849 (2^03, 2^04]: 258663 (2^04, 2^05]: 37478 sum_limbo_reclamation_event_size_total=3816775 count_limbo_reclamation_event_size_by_thread=6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6981 6982 6982 6982 6982 6982 6982 6982 6981 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6982 6981 6982 6982 count_limbo_reclamation_event_size_total=335133 sum_limbo_reclamation_event_count_total=335232 first_thread_announced_epoch_by_thread=14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 14152 sum_commit_tx_reader_total=145053586 sum_commit_tx_reader_by_thread=0 3053856 3007841 3010063 3003498 3001770 3040218 2994370 3049765 2969670 2998943 3036265 3050841 3018029 3010266 2976653 3037184 3004150 3034946 3141636 3051182 3015582 3009266 2998828 3095414 3044417 2991964 3013381 2978852 3047162 3063265 3091275 3027332 3021640 3016020 3023853 3100373 2976301 3013123 3037795 2976724 2995123 3029991 2989300 3043179 2991826 2961016 2998639 3010799 sum_commit_tx_writer_total=7635884 sum_commit_tx_writer_by_thread=0 161063 158020 158011 158090 158434 159400 157627 160887 155994 158348 159468 161041 158881 158192 156924 159666 157918 160288 165075 160398 158286 158089 157806 162542 159775 157929 158459 156884 160412 161854 162102 159319 158685 158640 160220 163623 156548 159584 159429 155974 158245 159736 158335 160242 157909 155118 158409 158005 sum_abort_tx_total=13194 sum_abort_tx_by_thread=0 274 281 250 265 278 270 259 244 273 258 276 270 261 262 311 301 266 285 275 267 293 233 281 241 291 276 295 285 267 279 278 296 260 291 273 258 275 267 276 266 327 266 263 315 285 259 283 289 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=10.6:5 17.2:30 23.8:217 30.4:2985 37:8349 43.6:13468 50.2:15839 56.8:5653 63.4:1321 70:130 [ 4.00, 10.60]: 5 ( 10.60, 17.20]: 30 ( 17.20, 23.80]: 217 ( 23.80, 30.40]: 2985 ( 30.40, 37.00]: 8349 ( 37.00, 43.60]: 13468 ( 43.60, 50.20]: 15839 ( 50.20, 56.80]: 5653 ( 56.80, 63.40]: 1321 ( 63.40, 70.00]: 130 linear_histogram_of_none_tx_wrset_size_full_data=1.4:16182 3.4:8020 [ 1.00, 1.40]: 16182 ( 3.00, 3.40]: 8020 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=162266 159754 157499 157832 156566 155304 158257 159209 159641 159220 158968 159824 163357 159761 156701 158193 159598 156593 161813 165507 158028 159512 158231 158333 160525 158553 160796 157432 157214 158771 160771 158390 158436 159055 159626 159008 162697 158809 157924 157147 160656 158259 159731 157077 158123 159991 157949 156950 sum_num_inserts_total=7633857 sum_num_deletes_by_thread=162588 160126 158043 157630 155962 155420 158822 159261 159502 159598 159613 158963 163471 159942 156606 158216 160652 155323 161494 165081 158165 159866 158125 158087 160980 158338 160554 158242 158252 157588 160400 157940 158726 158576 159630 157681 162385 158492 158469 156788 160433 157644 159614 155978 157193 159867 158240 157626 sum_num_deletes_total=7630192 sum_num_searches_by_thread=2928523 2883541 2834193 2832173 2820170 2805410 2857581 2854237 2878081 2867833 2865492 2870940 2937168 2884489 2819542 2852395 2887324 2813748 2901812 2976123 2844011 2875856 2837012 2845168 2890377 2851567 2889302 2850187 2846602 2840689 2893748 2855510 2856706 2859279 2880362 2851385 2932874 2863024 2850962 2821801 2890491 2840731 2876388 2820522 2836681 2876992 2841102 2835317 sum_num_searches_total=137425421 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3253377 3203421 3149735 3147635 3132698 3116134 3174660 3172707 3197224 3186651 3184073 3189727 3263996 3204192 3132849 3168804 3207574 3125664 3225119 3306711 3160204 3195234 3153368 3161588 3211882 3168458 3210652 3165861 3162068 3157048 3214919 3171840 3173868 3176910 3199618 3168074 3257956 3180325 3167355 3135736 3211580 3156634 3195733 3133577 3151997 3196850 3157291 3149893 average_num_operations_total=3181030 stdev_num_operations_total=5539 sum_num_operations_total=152689470 min_num_operations_total=3116134 max_num_operations_total=3306711 first_time_thread_terminate_by_thread=10000157 10000158 10000157 10000156 10000154 10000155 10000153 10000158 10000154 10000153 10000154 10000158 10000153 10000158 10000154 10000155 10000158 10000156 10000154 10000154 10000158 10000153 10000154 10000153 10000157 10000158 10000153 10000154 10000154 10000155 10000157 10000158 10000154 10000154 10000153 10000158 10000157 10000158 10000157 10000153 10000158 10000157 10000157 10000154 10000158 10000153 10000158 10000153 min_time_thread_terminate_total=10000153 max_time_thread_terminate_total=10000158 first_time_thread_start_by_thread=3 3 2 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=9988370452 threads_final_size=100032 final_keysum=9988370452 final_size=100032 validate_result=success Validation OK. Structural validation OK. total_find=137425421 total_rq=0 total_inserts=7633857 total_deletes=7630192 total_updates=15264049 total_queries=137425421 total_ops=152689470 find_throughput=13742542 rq_throughput=0 update_throughput=1526404 query_throughput=13742542 total_throughput=15268947 total find : 137425421 total rq : 0 total inserts : 7633857 total deletes : 7630192 total updates : 15264049 total queries : 137425421 total ops : 152689470 find throughput : 13742542 rq throughput : 0 update throughput : 1526404 query throughput : 13742542 total throughput : 15268947 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=9.03223 PAPI_L3_TCM=0.00061832 PAPI_TOT_CYC=7108.15 PAPI_TOT_INS=5377.98 garbage=6875177455577 total_execution_walltime=11.617s REALTIME_MAIN_END_PERF_FORMAT=7000025.842954526 noREC system shutdown: [time_cmd_output] time_elapsed_sec=11.74, faults_major=0, faults_minor=76805, mem_maxresident_kb=308940, user_cputime=529.88, sys_cputime=34.44, percent_cpu=4806% 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=1