__step=000101 __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.rhnorec_post -nwork 144 -nprefill 144 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000101.txt __path_data=data/data000101.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6994968.399570235 binary=./brown_abtree_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_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=20000000 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=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,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.017s prefilling_size=85732 prefilling_elapsed_millis=1.001s prefilling_size=94866 prefilling_elapsed_millis=1.101s prefilling_size=103699 prefilling_elapsed_millis=1.201s prefilling_size=112676 prefilling_elapsed_millis=1.301s prefilling_size=121509 prefilling_elapsed_millis=1.402s prefilling_size=130062 prefilling_elapsed_millis=1.502s prefilling_size=139148 prefilling_elapsed_millis=1.602s prefilling_size=147937 prefilling_elapsed_millis=1.702s prefilling_size=156779 prefilling_elapsed_millis=1.802s prefilling_size=165413 prefilling_elapsed_millis=1.903s prefilling_size=173989 prefilling_elapsed_millis=2.003s prefilling_size=182752 prefilling_elapsed_millis=2.103s prefilling_size=191773 prefilling_elapsed_millis=2.203s prefilling_size=200454 prefilling_elapsed_millis=2.303s prefilling_size=209616 prefilling_elapsed_millis=2.404s prefilling_size=218858 prefilling_elapsed_millis=2.504s prefilling_size=227755 prefilling_elapsed_millis=2.604s prefilling_size=236555 prefilling_elapsed_millis=2.704s prefilling_size=245350 prefilling_elapsed_millis=2.804s prefilling_size=254262 prefilling_elapsed_millis=2.905s prefilling_size=263084 prefilling_elapsed_millis=3.005s prefilling_size=271197 prefilling_elapsed_millis=3.105s prefilling_size=279843 prefilling_elapsed_millis=3.205s prefilling_size=288533 prefilling_elapsed_millis=3.305s prefilling_size=297143 prefilling_elapsed_millis=3.406s prefilling_size=305833 prefilling_elapsed_millis=3.506s prefilling_size=314678 prefilling_elapsed_millis=3.606s prefilling_size=323100 prefilling_elapsed_millis=3.706s prefilling_size=331690 prefilling_elapsed_millis=3.806s prefilling_size=340312 prefilling_elapsed_millis=3.907s prefilling_size=348717 prefilling_elapsed_millis=4.007s prefilling_size=357236 prefilling_elapsed_millis=4.107s prefilling_size=365773 prefilling_elapsed_millis=4.207s prefilling_size=374341 prefilling_elapsed_millis=4.307s prefilling_size=382722 prefilling_elapsed_millis=4.408s prefilling_size=391345 prefilling_elapsed_millis=4.508s prefilling_size=400148 prefilling_elapsed_millis=4.608s prefilling_size=408889 prefilling_elapsed_millis=4.708s prefilling_size=417624 prefilling_elapsed_millis=4.808s prefilling_size=426371 prefilling_elapsed_millis=4.909s prefilling_size=434749 prefilling_elapsed_millis=5.009s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 9565102 keys with 144 threads... tid=0 thread_prefill_amount=4164 percent_done_estimate=6.3 elapsed_s=7 est_remaining_s=244 / 252 tid=0 thread_prefill_amount=5552 percent_done_estimate=8.4 elapsed_s=10 est_remaining_s=237 / 247 tid=0 thread_prefill_amount=6940 percent_done_estimate=10.4 elapsed_s=12 est_remaining_s=228 / 240 tid=0 thread_prefill_amount=8328 percent_done_estimate=12.5 elapsed_s=14 est_remaining_s=220 / 234 tid=0 thread_prefill_amount=9716 percent_done_estimate=14.6 elapsed_s=17 est_remaining_s=218 / 235 tid=0 thread_prefill_amount=11104 percent_done_estimate=16.7 elapsed_s=19 est_remaining_s=215 / 234 tid=0 thread_prefill_amount=12492 percent_done_estimate=18.8 elapsed_s=21 est_remaining_s=208 / 230 tid=0 thread_prefill_amount=13880 percent_done_estimate=20.9 elapsed_s=24 est_remaining_s=205 / 229 tid=0 thread_prefill_amount=15268 percent_done_estimate=23.0 elapsed_s=26 est_remaining_s=199 / 225 tid=0 thread_prefill_amount=16656 percent_done_estimate=25.1 elapsed_s=29 est_remaining_s=193 / 222 tid=0 thread_prefill_amount=18044 percent_done_estimate=27.2 elapsed_s=31 est_remaining_s=187 / 218 tid=0 thread_prefill_amount=19432 percent_done_estimate=29.3 elapsed_s=33 est_remaining_s=180 / 213 tid=0 thread_prefill_amount=20820 percent_done_estimate=31.3 elapsed_s=36 est_remaining_s=175 / 211 tid=0 thread_prefill_amount=22208 percent_done_estimate=33.4 elapsed_s=38 est_remaining_s=169 / 207 tid=0 thread_prefill_amount=23596 percent_done_estimate=35.5 elapsed_s=40 est_remaining_s=164 / 204 tid=0 thread_prefill_amount=24984 percent_done_estimate=37.6 elapsed_s=42 est_remaining_s=159 / 201 tid=0 thread_prefill_amount=26372 percent_done_estimate=39.7 elapsed_s=45 est_remaining_s=153 / 198 tid=0 thread_prefill_amount=27760 percent_done_estimate=41.8 elapsed_s=47 est_remaining_s=148 / 195 tid=0 thread_prefill_amount=29148 percent_done_estimate=43.9 elapsed_s=50 est_remaining_s=143 / 193 tid=0 thread_prefill_amount=30536 percent_done_estimate=46.0 elapsed_s=52 est_remaining_s=137 / 189 tid=0 thread_prefill_amount=31924 percent_done_estimate=48.1 elapsed_s=54 est_remaining_s=132 / 187 tid=0 thread_prefill_amount=33312 percent_done_estimate=50.2 elapsed_s=57 est_remaining_s=127 / 184 tid=0 thread_prefill_amount=34700 percent_done_estimate=52.2 elapsed_s=59 est_remaining_s=122 / 181 tid=0 thread_prefill_amount=36088 percent_done_estimate=54.3 elapsed_s=62 est_remaining_s=117 / 178 tid=0 thread_prefill_amount=37476 percent_done_estimate=56.4 elapsed_s=64 est_remaining_s=111 / 175 tid=0 thread_prefill_amount=38864 percent_done_estimate=58.5 elapsed_s=66 est_remaining_s=106 / 172 tid=0 thread_prefill_amount=40252 percent_done_estimate=60.6 elapsed_s=69 est_remaining_s=100 / 169 tid=0 thread_prefill_amount=41640 percent_done_estimate=62.7 elapsed_s=71 est_remaining_s=95 / 166 tid=0 thread_prefill_amount=43028 percent_done_estimate=64.8 elapsed_s=73 est_remaining_s=90 / 163 tid=0 thread_prefill_amount=44416 percent_done_estimate=66.9 elapsed_s=76 est_remaining_s=85 / 160 tid=0 thread_prefill_amount=45804 percent_done_estimate=69.0 elapsed_s=78 est_remaining_s=79 / 157 tid=0 thread_prefill_amount=47192 percent_done_estimate=71.0 elapsed_s=81 est_remaining_s=74 / 154 tid=0 thread_prefill_amount=48580 percent_done_estimate=73.1 elapsed_s=83 est_remaining_s=69 / 152 tid=0 thread_prefill_amount=49968 percent_done_estimate=75.2 elapsed_s=85 est_remaining_s=63 / 148 tid=0 thread_prefill_amount=51356 percent_done_estimate=77.3 elapsed_s=88 est_remaining_s=58 / 145 tid=0 thread_prefill_amount=52744 percent_done_estimate=79.4 elapsed_s=90 est_remaining_s=52 / 142 tid=0 thread_prefill_amount=54132 percent_done_estimate=81.5 elapsed_s=92 est_remaining_s=47 / 139 tid=0 thread_prefill_amount=55520 percent_done_estimate=83.6 elapsed_s=95 est_remaining_s=42 / 136 tid=0 thread_prefill_amount=56908 percent_done_estimate=85.7 elapsed_s=97 est_remaining_s=36 / 133 tid=0 thread_prefill_amount=58296 percent_done_estimate=87.8 elapsed_s=99 est_remaining_s=31 / 130 tid=0 thread_prefill_amount=59684 percent_done_estimate=89.9 elapsed_s=102 est_remaining_s=26 / 128 tid=0 thread_prefill_amount=61072 percent_done_estimate=91.9 elapsed_s=104 est_remaining_s=21 / 125 tid=0 thread_prefill_amount=62460 percent_done_estimate=94.0 elapsed_s=107 est_remaining_s=15 / 122 tid=0 thread_prefill_amount=63848 percent_done_estimate=96.1 elapsed_s=109 est_remaining_s=10 / 119 tid=0 thread_prefill_amount=65236 percent_done_estimate=98.2 elapsed_s=110 est_remaining_s=5 / 115 tid=0 thread_prefill_amount=66624 percent_done_estimate=100.3 elapsed_s=111 est_remaining_s=-1 / 110 tid=0 thread_prefill_amount=68012 percent_done_estimate=102.4 elapsed_s=112 est_remaining_s=-6 / 106 timing_elapsed 106.671s prefilling_size=10000000 prefilling_elapsed_millis=106.926s finished prefilling to size 10000000 for expected size 10000000 keysum=100007578954620, performing 14315435 updates; total_prefilling_elapsed_ms=106926 ms) pref_size=10000000 pref_millis=106926 prefill_elapsed_ms=106926 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6995080.724169092 REALTIME_END_PERF_FORMAT=6995090.724405312 ############################################################################### ################################## 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 2859 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.039s tree_stats_numInternalsAtDepth=1 1 8 50 395 2859 21553 168192 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1328997 tree_stats_numNodesAtDepth=1 1 8 50 395 2859 21553 168192 1328997 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10000948 tree_stats_avgDegreeAtDepth=1 8 6.25 7.9 7.23797 7.53865 7.80365 7.90167 7.52518 tree_stats_height=9 tree_stats_numInternals=193059 tree_stats_numLeaves=1328997 tree_stats_numNodes=1522056 tree_stats_numKeys=10000948 tree_stats_avgDegreeInternal=7.88389 tree_stats_avgDegreeLeaves=7.52518 tree_stats_avgDegree=7.57068 tree_stats_avgKeyDepth=8 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=21310 first_thread_announced_epoch_by_thread=572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 572 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=20276508 sum_fasthtm_abort_total=45674363 sum_htmpostfix_commit_total=1051119 sum_htmpostfix_abort_total=3526296 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=52494636 sum_stmprefix_commit_total=2 sum_slow_commit_total=17228 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=7306 7178 7568 7455 6153 7505 7086 7134 7389 7360 5999 7647 7199 7252 7308 7213 5997 7604 7278 7454 6972 7097 6333 7846 7145 7148 7220 7300 6001 7419 6833 7151 7101 7247 6026 7756 6943 7159 7049 7112 5792 7603 7147 7129 7006 7008 6001 7671 7767 7447 7411 7610 5832 6134 7387 7447 7463 7186 6199 7876 7408 7247 7328 7402 6278 7804 7067 7171 7577 7519 5691 7435 7377 7152 7180 7478 6100 5981 7204 6991 7485 7230 6070 7768 7265 7279 7380 7189 6027 7580 6911 6925 7234 7376 5430 7349 8224 8279 8423 7157 7061 8545 8625 8602 8682 7335 8132 8460 8451 8547 8234 6923 8084 8303 8362 8670 8778 6725 8588 8261 8212 8275 8292 6963 6784 8395 8487 8424 8481 7056 8140 8577 8384 8409 8057 6758 8494 8221 8464 8423 8534 6629 8632 8349 sum_num_inserts_total=1067838 sum_num_deletes_by_thread=7337 7181 7471 7307 6218 7345 7166 7208 7304 7421 6140 7616 7293 7224 7244 7032 5919 7338 7147 7323 6841 7053 6137 7625 7086 7183 7265 7189 6009 7515 7002 7156 7103 7213 6164 7635 6948 7225 7073 7015 5903 7432 7060 6884 7041 7131 6108 7610 7524 7590 7355 7589 5795 6088 7300 7338 7582 7339 6196 7722 7422 7441 7492 7314 6231 7663 7196 7113 7585 7359 5582 7324 7463 7330 7258 7513 6084 5838 7478 7291 7427 7409 6088 7579 7243 7001 7418 7012 5859 7605 7007 7023 7331 7236 5236 7331 8325 8311 8687 7220 7003 8484 8728 8618 8715 7434 8279 8373 8255 8394 8244 6824 8475 8189 8450 8493 8578 6767 8784 8584 8325 8221 8220 6998 6939 8412 8466 8296 8494 6929 8111 8723 8407 8382 8200 6930 8528 8189 8322 8607 8540 6672 8628 8237 sum_num_deletes_total=1067030 sum_num_searches_by_thread=131199 130343 134756 132707 111506 133718 128821 128507 130833 133076 108864 136565 130500 132102 129517 128098 106968 135576 130718 133184 124815 127368 112217 139377 130087 127212 130433 130023 107814 134034 125002 128519 130323 129590 109616 139315 125181 128904 127930 125233 105618 136033 126478 126518 127148 127564 108280 137701 139219 136635 131590 136650 104092 110382 130999 133238 134179 130837 111235 140492 133369 133330 133372 131135 112893 139259 126906 127393 135608 134301 101153 130475 134029 130707 129914 133546 107260 108353 130765 128032 133231 129677 108980 139212 133052 129012 129967 128225 107570 136735 126023 127676 130921 131284 96561 132745 149026 151190 154743 129520 126938 153515 155519 155219 157061 134192 146320 152961 151407 153362 148207 122599 148506 146034 150461 154434 156500 119818 158138 149307 149410 146666 149010 126209 123613 151231 151303 151423 152998 127109 146229 153861 151679 153535 144634 123318 150698 146300 152477 151592 154435 120767 157600 150805 sum_num_searches_total=19209989 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=145842 144702 149795 147469 123877 148568 143073 142849 145526 147857 121003 151828 144992 146578 144069 142343 118884 150518 145143 147961 138628 141518 124687 154848 144318 141543 144918 144512 119824 148968 138837 142826 144527 144050 121806 154706 139072 143288 142052 139360 117313 151068 140685 140531 141195 141703 120389 152982 154510 151672 146356 151849 115719 122604 145686 148023 149224 145362 123630 156090 148199 148018 148192 145851 125402 154726 141169 141677 150770 149179 112426 145234 148869 145189 144352 148537 119444 120172 145447 142314 148143 144316 121138 154559 147560 143292 144765 142426 119456 151920 139941 141624 145486 145896 107227 147425 165575 167780 171853 143897 141002 170544 172872 172439 174458 148961 162731 169794 168113 170303 164685 136346 165065 162526 167273 171597 173856 133310 175510 166152 165947 163162 165522 140170 137336 168038 168256 168143 169973 141094 162480 171161 168470 170326 160891 137006 167720 162710 169263 168622 171509 134068 174860 167391 average_num_operations_total=148228 stdev_num_operations_total=1282 sum_num_operations_total=21344857 min_num_operations_total=107227 max_num_operations_total=175510 first_time_thread_terminate_by_thread=10003653 10000236 10003817 10000972 10003828 10003838 10003643 10003671 10000562 10000979 10003685 10003840 10001097 10003919 10001093 10003683 10003638 10000531 10003673 10000481 10003803 10000235 10000260 10000955 10003640 10003896 10003824 10003633 10000458 10003822 10000489 10000373 10003820 10001076 10000556 10003692 10000459 10003842 10000552 10000586 10000565 10003811 10003800 10000948 10003832 10000235 10000235 10003636 10000546 10000384 10005545 10003657 10003805 10000977 10000505 10003790 10001081 10000233 10001105 10003647 10001042 10001047 10003623 10000553 10000245 10000586 10000252 10003693 10003670 10003628 10000471 10001074 10003674 10000355 10000573 10003836 10003792 10000232 10003800 10000996 10000549 10000233 10003651 10001097 10001091 10000385 10000335 10000242 10000240 10000235 10001047 10001071 10000588 10001044 10000508 10001009 10000321 10000593 10000574 10000302 10000975 10003665 10000476 10005552 10000582 10000273 10000240 10000426 10000239 10000986 10000281 10000516 10001062 10000394 10001079 10000573 10000993 10000570 10001039 10000307 10000985 10000235 10001057 10000524 10000336 10000245 10000234 10000993 10001086 10003850 10000240 10000337 10000536 10001060 10000406 10000412 10001043 10000234 10000990 10000479 10001067 10000423 10000394 10001052 min_time_thread_terminate_total=10000232 max_time_thread_terminate_total=10005552 first_time_thread_start_by_thread=9 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 6 5 5 5 5 6 6 6 5 5 5 5 6 5 5 5 5 6 6 5 5 5 5 5 5 5 5 5 6 6 6 6 5 5 5 5 6 5 5 5 5 6 6 5 5 5 5 5 5 5 5 5 6 min_time_thread_start_total=4 max_time_thread_start_total=9 sum_duration_all_ops_total= threads_final_keysum=100013732841256 threads_final_size=10000948 final_keysum=100013732841256 final_size=10000948 validate_result=success Validation OK. Structural validation OK. total_find=19209989 total_rq=0 total_inserts=1067838 total_deletes=1067030 total_updates=2134868 total_queries=19209989 total_ops=21344857 find_throughput=1920998 rq_throughput=0 update_throughput=213486 query_throughput=1920998 total_throughput=2134485 total find : 19209989 total rq : 0 total inserts : 1067838 total deletes : 1067030 total updates : 2134868 total queries : 19209989 total ops : 21344857 find throughput : 1920998 rq throughput : 0 update throughput : 213486 query throughput : 1920998 total throughput : 2134485 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=44.7966 PAPI_L3_TCM=15.4862 PAPI_TOT_CYC=169220 PAPI_TOT_INS=4540.41 garbage=89841640468154 total_execution_walltime=122.488s REALTIME_MAIN_END_PERF_FORMAT=6995090.888627925 RH noREC w/postfix htm system shutdown: HTM_ATTEMPT_THRESH=40 [time_cmd_output] time_elapsed_sec=122.96, faults_major=0, faults_minor=1266243, mem_maxresident_kb=5060848, user_cputime=16976.16, sys_cputime=227.97, percent_cpu=13990% timeout=false algorithm=brown_abtree_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