__step=000278 __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 144 -nprefill 144 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000278.txt __path_data=data/data000278.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6997999.295004201 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=2000000 PREFILL_THREADS=144 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=144 WORK_THREADS=144 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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63,64,65,66,67,68,69,70,71,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167 RH noREC w/postfix htm system ready Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.045s prefilling_size=104399 prefilling_elapsed_millis=1.001s prefilling_size=115149 prefilling_elapsed_millis=1.101s prefilling_size=125705 prefilling_elapsed_millis=1.202s prefilling_size=136023 prefilling_elapsed_millis=1.302s prefilling_size=146226 prefilling_elapsed_millis=1.403s prefilling_size=156580 prefilling_elapsed_millis=1.503s prefilling_size=166761 prefilling_elapsed_millis=1.603s prefilling_size=176846 prefilling_elapsed_millis=1.704s prefilling_size=186564 prefilling_elapsed_millis=1.804s prefilling_size=196301 prefilling_elapsed_millis=1.905s prefilling_size=206101 prefilling_elapsed_millis=2.005s prefilling_size=215537 prefilling_elapsed_millis=2.106s prefilling_size=225118 prefilling_elapsed_millis=2.206s prefilling_size=234625 prefilling_elapsed_millis=2.307s prefilling_size=243888 prefilling_elapsed_millis=2.407s prefilling_size=253107 prefilling_elapsed_millis=2.508s prefilling_size=261990 prefilling_elapsed_millis=2.608s prefilling_size=271026 prefilling_elapsed_millis=2.709s prefilling_size=279982 prefilling_elapsed_millis=2.809s prefilling_size=288743 prefilling_elapsed_millis=2.91s prefilling_size=297507 prefilling_elapsed_millis=3.01s prefilling_size=306247 prefilling_elapsed_millis=3.111s prefilling_size=314517 prefilling_elapsed_millis=3.211s prefilling_size=322744 prefilling_elapsed_millis=3.312s prefilling_size=330907 prefilling_elapsed_millis=3.412s prefilling_size=339045 prefilling_elapsed_millis=3.513s prefilling_size=347032 prefilling_elapsed_millis=3.613s prefilling_size=355032 prefilling_elapsed_millis=3.714s prefilling_size=362909 prefilling_elapsed_millis=3.815s prefilling_size=370753 prefilling_elapsed_millis=3.915s prefilling_size=378572 prefilling_elapsed_millis=4.016s prefilling_size=386318 prefilling_elapsed_millis=4.116s prefilling_size=394135 prefilling_elapsed_millis=4.217s prefilling_size=401647 prefilling_elapsed_millis=4.317s prefilling_size=409051 prefilling_elapsed_millis=4.418s prefilling_size=416222 prefilling_elapsed_millis=4.518s prefilling_size=423428 prefilling_elapsed_millis=4.619s prefilling_size=430535 prefilling_elapsed_millis=4.72s prefilling_size=437639 prefilling_elapsed_millis=4.82s prefilling_size=444458 prefilling_elapsed_millis=4.921s prefilling_size=451219 prefilling_elapsed_millis=5.022s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 548680 keys with 144 threads... tid=0 thread_prefill_amount=4164 percent_done_estimate=109.3 elapsed_s=6 est_remaining_s=-1 / 5 tid=0 thread_prefill_amount=5552 percent_done_estimate=145.7 elapsed_s=8 est_remaining_s=-6 / 2 tid=0 thread_prefill_amount=6940 percent_done_estimate=182.1 elapsed_s=10 est_remaining_s=-10 / -0 timing_elapsed 4.56172s prefilling_size=1000000 prefilling_elapsed_millis=4.572s finished prefilling to size 1000000 for expected size 1000000 keysum=1000214376481, performing 2081817 updates; total_prefilling_elapsed_ms=4572 ms) pref_size=1000000 pref_millis=4572 prefill_elapsed_ms=4572 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6998009.268166884 REALTIME_END_PERF_FORMAT=6998019.268361010 ############################################################################### ################################## 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 (144 threads)... partitioned into 1024 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.016s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32758 64742 116447 155244 121743 43996 5402 112 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 10 707 11247 62852 144358 143467 55714 6899 134 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65449 127694 218096 266101 187463 61116 7011 134 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65449 127694 218096 266101 187463 61116 7011 134 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99765 1.96185 1.79604 1.50829 1.24697 1.09132 1.02633 1.00314 1 tree_stats_height=24 tree_stats_numInternals=573211 tree_stats_numLeaves=425388 tree_stats_numNodes=998599 tree_stats_numKeys=998599 tree_stats_avgDegreeInternal=1.74211 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42598 tree_stats_avgKeyDepth=18.3328 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=23658 first_thread_announced_epoch_by_thread=350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 350 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=22588362 sum_fasthtm_abort_total=49650513 sum_htmpostfix_commit_total=1161088 sum_htmpostfix_abort_total=4575236 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=53445728 sum_stmprefix_commit_total=56 sum_slow_commit_total=27113 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=8182 8327 8326 7445 8610 8525 8464 8314 8679 7680 8697 8759 8470 8396 8612 7522 8310 8500 8402 8428 8371 6846 8794 8881 8311 8279 8563 7238 8583 8303 8337 8366 8611 7484 8897 8712 8214 8442 8438 7271 8506 8515 8452 8415 8289 6485 8663 8723 8133 8019 7734 6877 8154 8054 8028 8224 8093 7326 8223 8400 8167 8062 7643 6941 7789 8349 7731 7748 8253 6706 8375 8276 7925 7918 7929 6841 8186 7717 7993 8075 8024 7021 7996 8062 8156 8086 7878 6764 7861 7994 7556 7721 8018 6823 8208 8066 8574 8824 9090 7886 7767 9374 8770 8844 9071 7981 8765 9170 8872 8841 8765 7262 8751 8886 9129 8999 9028 7232 9105 9107 8605 8613 8843 7738 7793 9018 8627 8544 8904 7915 8772 9075 8787 8697 8807 7297 8805 8702 9033 8987 8890 7226 9123 9036 sum_num_inserts_total=1187690 sum_num_deletes_by_thread=8332 8311 8408 7479 8621 8329 8513 8591 8680 7668 8677 8954 8388 8584 8762 7521 8427 8430 8215 8387 8527 7034 8682 8822 8085 8280 8443 7655 8647 8523 8462 8398 8625 7557 8675 8902 8465 8489 8362 7289 8536 8612 8344 8389 8304 6601 8519 8644 7906 8062 7904 6855 8247 8034 8208 8212 8283 7260 8239 8230 8184 8284 7800 7077 8003 8076 7770 7782 8151 6753 8229 8202 8039 7922 7884 6771 8125 7838 8015 7880 8098 7219 7954 8330 8213 8030 7558 6811 7893 8108 7547 7649 8111 6706 8056 8255 8765 8643 8899 7779 7867 9069 8800 8798 8942 8108 8975 9096 8886 8959 8798 7453 8888 8975 9032 9018 8981 7129 9280 8982 8672 8644 8831 7659 7784 8953 8861 8682 8724 8065 8874 9107 8777 8834 8682 7363 8532 8737 9102 9047 9111 7177 9072 8900 sum_num_deletes_total=1190223 sum_num_searches_by_thread=146259 151072 151215 134323 155686 152407 152730 151795 155290 137950 155811 158922 150694 152888 155467 133790 149908 151452 148456 152462 150835 123630 155366 159842 147926 150750 154372 135109 154573 152610 151001 150102 154455 137071 156602 157445 152736 153345 152958 132200 152093 154545 150359 150217 148422 119251 153882 156621 146042 145472 142181 124569 146263 144647 145152 146768 148210 130275 150520 148353 148457 146834 140763 126606 139786 145425 140580 138545 147026 120696 148753 148499 144534 141207 140496 123358 147384 140521 142902 145267 145646 127614 142926 147593 146388 144770 138077 121734 141704 146639 133911 139533 146785 122177 144354 146973 155400 156939 161999 142750 140607 164196 158059 157192 164354 146687 160512 164630 158910 160162 156515 130530 158223 160792 163356 161982 165674 128972 163585 165525 158437 156500 158258 136598 138964 160977 155441 157125 157266 142371 159106 164034 158167 157252 158802 131467 155021 158611 166084 162652 163876 131307 161947 161082 sum_num_searches_total=21398706 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=162773 167710 167949 149247 172917 169261 169707 168700 172649 153298 173185 176635 167552 169868 172841 148833 166645 168382 165073 169277 167733 137510 172842 177545 164322 167309 171378 150002 171803 169436 167800 166866 171691 152112 174174 175059 169415 170276 169758 146760 169135 171672 167155 167021 165015 132337 171064 173988 162081 161553 157819 138301 162664 160735 161388 163204 164586 144861 166982 164983 164808 163180 156206 140624 155578 161850 156081 154075 163430 134155 165357 164977 160498 157047 156309 136970 163695 156076 158910 161222 161768 141854 158876 163985 162757 160886 153513 135309 157458 162741 149014 154903 162914 135706 160618 163294 172739 174406 179988 158415 156241 182639 175629 174834 182367 162776 178252 182896 176668 177962 174078 145245 175862 178653 181517 179999 183683 143333 181970 183614 175714 173757 175932 151995 154541 178948 172929 174351 174894 158351 176752 182216 175731 174783 176291 146127 172358 176050 184219 180686 181877 145710 180142 179018 average_num_operations_total=165115 stdev_num_operations_total=1004 sum_num_operations_total=23776619 min_num_operations_total=132337 max_num_operations_total=184219 first_time_thread_terminate_by_thread=10009192 10000262 10000188 10000482 10007119 10000420 10000489 10007170 10000504 10000191 10000238 10000419 10000420 10007122 10009203 10000362 10000187 10001045 10009224 10000515 10000489 10000414 10000502 10000473 10007122 10000380 10009184 10000339 10009184 10000213 10007124 10007095 10000187 10000191 10000448 10007056 10000263 10000195 10009193 10000310 10000512 10009189 10009226 10007092 10000518 10000493 10000186 10007113 10009227 10007063 10009216 10007073 10000196 10009230 10007061 10000211 10009177 10009229 10009205 10009213 10009220 10007169 10009202 10007108 10009212 10007173 10009188 10001067 10009196 10009218 10009226 10007114 10009215 10000364 10009180 10007116 10007162 10009223 10000508 10007155 10007151 10009210 10007162 10007176 10009232 10009211 10007150 10000255 10009207 10000366 10009201 10000273 10007140 10009227 10009210 10000275 10007072 10007064 10007094 10007135 10007130 10000447 10000275 10000509 10000428 10000304 10001049 10007101 10000189 10001057 10000421 10007176 10000188 10000350 10001017 10000464 10007080 10000209 10001030 10007156 10000440 10000463 10000231 10000448 10001038 10001054 10000246 10000203 10001008 10009187 10000418 10007086 10007062 10000236 10000207 10001033 10000207 10001025 10007102 10007168 10007094 10007125 10007172 10000434 min_time_thread_terminate_total=10000186 max_time_thread_terminate_total=10009232 first_time_thread_start_by_thread=9 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 5 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 5 4 4 4 4 4 4 4 5 4 5 4 4 5 5 5 5 5 5 4 5 5 5 5 4 4 5 5 5 5 5 4 4 4 5 4 4 5 5 5 5 5 5 4 5 5 5 5 4 4 5 5 5 5 5 5 5 4 5 4 5 4 4 5 5 5 5 5 4 5 4 5 5 4 5 5 4 4 5 5 5 4 5 4 5 4 4 5 5 5 5 5 4 5 4 5 5 4 5 5 4 4 5 min_time_thread_start_total=3 max_time_thread_start_total=9 sum_duration_all_ops_total= threads_final_keysum=998694791789 threads_final_size=998599 final_keysum=998694791789 final_size=998599 validate_result=success Validation OK. Structural validation OK. total_find=21398706 total_rq=0 total_inserts=1187690 total_deletes=1190223 total_updates=2377913 total_queries=21398706 total_ops=23776619 find_throughput=2139870 rq_throughput=0 update_throughput=237791 query_throughput=2139870 total_throughput=2377661 total find : 21398706 total rq : 0 total inserts : 1187690 total deletes : 1190223 total updates : 2377913 total queries : 21398706 total ops : 23776619 find throughput : 2139870 rq throughput : 0 update throughput : 237791 query throughput : 2139870 total throughput : 2377661 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=39.1419 PAPI_L3_TCM=11.4086 PAPI_TOT_CYC=151154 PAPI_TOT_INS=4237.22 garbage=9961043099590 total_execution_walltime=20.11s REALTIME_MAIN_END_PERF_FORMAT=6998019.406099898 RH noREC w/postfix htm system shutdown: HTM_ATTEMPT_THRESH=40 [time_cmd_output] time_elapsed_sec=20.24, faults_major=0, faults_minor=141170, mem_maxresident_kb=560872, user_cputime=2808.64, sys_cputime=37.21, percent_cpu=14054% 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=2