__step=000489 __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_sigouin_int_avl_tm_auto.debra.rhnorec_post -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=data000489.txt __path_data=data/data000489.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=7000694.221227468 binary=./brown_sigouin_int_avl_tm_auto.debra.rhnorec_post parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_sigouin_int_avl_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=48 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 RH noREC w/postfix htm system ready Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.006s prefilling_size=92385 prefilling_elapsed_millis=1.001s prefilling_size=93884 prefilling_elapsed_millis=1.101s prefilling_size=94899 prefilling_elapsed_millis=1.201s prefilling_size=96128 prefilling_elapsed_millis=1.301s prefilling_size=96727 prefilling_elapsed_millis=1.402s prefilling_size=97785 prefilling_elapsed_millis=1.502s prefilling_size=97980 prefilling_elapsed_millis=1.602s prefilling_size=98368 prefilling_elapsed_millis=1.702s finished prefilling to size 98371 for expected size 100000 keysum=9850042854, performing 1010531 updates; total_prefilling_elapsed_ms=1803 ms) pref_size=98371 pref_millis=1803 prefill_elapsed_ms=1824 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=7000696.256307339 REALTIME_END_PERF_FORMAT=7000706.256463228 ############################################################################### ################################## 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 1024 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.038s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8114 14249 16691 8400 1378 60 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 73 1713 9581 18546 10624 1750 74 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8187 15962 26272 26946 12002 1810 74 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8187 15962 26272 26946 12002 1810 74 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 1.99878 1.95859 1.75323 1.39034 1.13367 1.03599 1.00773 1 tree_stats_height=20 tree_stats_numInternals=57083 tree_stats_numLeaves=42361 tree_stats_numNodes=99444 tree_stats_numKeys=99444 tree_stats_avgDegreeInternal=1.74209 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42598 tree_stats_avgKeyDepth=14.9154 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=180384 first_thread_announced_epoch_by_thread=7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 7616 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=51756271 sum_fasthtm_abort_total=109722867 sum_htmpostfix_commit_total=2653234 sum_htmpostfix_abort_total=16119177 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=77940823 sum_stmprefix_commit_total=514 sum_slow_commit_total=67887 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=55986 55468 57035 53271 57473 56948 57055 56545 57870 54098 58247 58151 56636 57432 56942 53110 57942 58920 57216 56476 57276 53901 58424 58474 56439 55583 57230 53292 57640 57468 57232 57389 58437 53600 58061 58611 56737 57092 57065 53322 57500 58547 57111 56472 57753 53762 58648 58696 sum_num_inserts_total=2724583 sum_num_deletes_by_thread=56549 55709 56876 53628 57832 57025 56878 56995 58316 54005 58137 58350 56829 57125 57066 53107 57822 58644 56996 56192 56835 54527 58396 58453 56274 55320 57527 53587 58273 57701 57380 57390 58204 54311 57772 58435 56634 57326 57181 53484 57607 58471 57136 56689 57626 53497 57933 58580 sum_num_deletes_total=2726630 sum_num_searches_by_thread=1014331 996215 1022313 959839 1038552 1026756 1022282 1025432 1045411 977982 1048524 1049347 1022435 1035144 1019378 958302 1044929 1055458 1030719 1013907 1028273 972263 1051943 1049795 1014548 1001454 1030860 960488 1037251 1029764 1028400 1034467 1055799 968592 1043317 1052422 1021967 1027312 1029620 962046 1031951 1053337 1026128 1010380 1033556 957825 1052295 1053384 sum_num_searches_total=49026693 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=1126866 1107392 1136224 1066738 1153857 1140729 1136215 1138972 1161597 1086085 1164908 1165848 1135900 1149701 1133386 1064519 1160693 1173022 1144931 1126575 1142384 1080691 1168763 1166722 1127261 1112357 1145617 1067367 1153164 1144933 1143012 1149246 1172440 1076503 1159150 1169468 1135338 1141730 1143866 1068852 1147058 1170355 1140375 1123541 1148935 1065084 1168876 1170660 average_num_operations_total=1134956 stdev_num_operations_total=4736 sum_num_operations_total=54477906 min_num_operations_total=1064519 max_num_operations_total=1173022 first_time_thread_terminate_by_thread=10000223 10000237 10000181 10000208 10000223 10000198 10000170 10000231 10000229 10000155 10000163 10000225 10000173 10000233 10000200 10000176 10000210 10000198 10000193 10000186 10000226 10000204 10000220 10000202 10000168 10000168 10000234 10000194 10000165 10000227 10000191 10000232 10000221 10000206 10000214 10000235 10000155 10000216 10000236 10000171 10000237 10000219 10000155 10000183 10000214 10000230 10000211 10000239 min_time_thread_terminate_total=10000155 max_time_thread_terminate_total=10000239 first_time_thread_start_by_thread=7 7 7 7 7 7 7 7 7 8 8 8 7 7 7 7 7 7 7 7 7 7 8 7 7 7 7 7 7 7 7 7 7 7 8 8 7 7 7 7 7 7 7 7 7 7 8 7 min_time_thread_start_total=7 max_time_thread_start_total=8 sum_duration_all_ops_total= threads_final_keysum=9935807785 threads_final_size=99444 final_keysum=9935807785 final_size=99444 validate_result=success Validation OK. Structural validation OK. total_find=49026693 total_rq=0 total_inserts=2724583 total_deletes=2726630 total_updates=5451213 total_queries=49026693 total_ops=54477906 find_throughput=4902669 rq_throughput=0 update_throughput=545121 query_throughput=4902669 total_throughput=5447790 total find : 49026693 total rq : 0 total inserts : 2724583 total deletes : 2726630 total updates : 5451213 total queries : 49026693 total ops : 54477906 find throughput : 4902669 rq throughput : 0 update throughput : 545121 query throughput : 4902669 total throughput : 5447790 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=27.6616 PAPI_L3_TCM=0.315842 PAPI_TOT_CYC=20859.8 PAPI_TOT_INS=3725.68 garbage=2448747107602 total_execution_walltime=12.233s REALTIME_MAIN_END_PERF_FORMAT=7000706.469694859 RH noREC w/postfix htm system shutdown: HTM_ATTEMPT_THRESH=40 [time_cmd_output] time_elapsed_sec=12.38, faults_major=0, faults_minor=121912, mem_maxresident_kb=489496, user_cputime=576.19, sys_cputime=21.05, percent_cpu=4822% timeout=false algorithm=brown_sigouin_int_avl_tm_auto.debra.rhnorec_post thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1