__step=000318 __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 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000318.txt __path_data=data/data000318.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6998618.325377587 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=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=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.014s prefilling_size=100590 prefilling_elapsed_millis=1.001s prefilling_size=110838 prefilling_elapsed_millis=1.101s prefilling_size=120689 prefilling_elapsed_millis=1.201s prefilling_size=130694 prefilling_elapsed_millis=1.302s prefilling_size=141074 prefilling_elapsed_millis=1.402s prefilling_size=151209 prefilling_elapsed_millis=1.502s prefilling_size=161311 prefilling_elapsed_millis=1.602s prefilling_size=170849 prefilling_elapsed_millis=1.702s prefilling_size=180448 prefilling_elapsed_millis=1.803s prefilling_size=189270 prefilling_elapsed_millis=1.903s prefilling_size=199007 prefilling_elapsed_millis=2.003s prefilling_size=208534 prefilling_elapsed_millis=2.103s prefilling_size=217703 prefilling_elapsed_millis=2.203s prefilling_size=226834 prefilling_elapsed_millis=2.303s prefilling_size=236114 prefilling_elapsed_millis=2.403s prefilling_size=245500 prefilling_elapsed_millis=2.504s prefilling_size=254804 prefilling_elapsed_millis=2.604s prefilling_size=264328 prefilling_elapsed_millis=2.704s prefilling_size=273313 prefilling_elapsed_millis=2.804s prefilling_size=282085 prefilling_elapsed_millis=2.904s prefilling_size=290353 prefilling_elapsed_millis=3.005s prefilling_size=298573 prefilling_elapsed_millis=3.105s prefilling_size=307044 prefilling_elapsed_millis=3.205s prefilling_size=315502 prefilling_elapsed_millis=3.305s prefilling_size=323763 prefilling_elapsed_millis=3.406s prefilling_size=332050 prefilling_elapsed_millis=3.506s prefilling_size=340318 prefilling_elapsed_millis=3.606s prefilling_size=348458 prefilling_elapsed_millis=3.706s prefilling_size=356607 prefilling_elapsed_millis=3.806s prefilling_size=364570 prefilling_elapsed_millis=3.907s prefilling_size=372526 prefilling_elapsed_millis=4.007s prefilling_size=380778 prefilling_elapsed_millis=4.107s prefilling_size=388378 prefilling_elapsed_millis=4.207s prefilling_size=396247 prefilling_elapsed_millis=4.307s prefilling_size=403747 prefilling_elapsed_millis=4.407s prefilling_size=411441 prefilling_elapsed_millis=4.507s prefilling_size=419249 prefilling_elapsed_millis=4.608s prefilling_size=426925 prefilling_elapsed_millis=4.708s prefilling_size=434478 prefilling_elapsed_millis=4.808s prefilling_size=441936 prefilling_elapsed_millis=4.908s prefilling_size=449404 prefilling_elapsed_millis=5.009s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 550491 keys with 144 threads... tid=0 thread_prefill_amount=4164 percent_done_estimate=108.9 elapsed_s=6 est_remaining_s=-1 / 5 tid=0 thread_prefill_amount=5552 percent_done_estimate=145.2 elapsed_s=8 est_remaining_s=-6 / 2 tid=0 thread_prefill_amount=6940 percent_done_estimate=181.5 elapsed_s=10 est_remaining_s=-10 / -0 timing_elapsed 5.07969s prefilling_size=1000000 prefilling_elapsed_millis=5.091s finished prefilling to size 1000000 for expected size 1000000 keysum=999856754975, performing 2067180 updates; total_prefilling_elapsed_ms=5092 ms) pref_size=1000000 pref_millis=5092 prefill_elapsed_ms=5092 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6998628.764159862 REALTIME_END_PERF_FORMAT=6998638.764398858 ############################################################################### ################################## 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 2611 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.033s tree_stats_numInternalsAtDepth=1 1 6 45 329 2611 20224 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 159350 tree_stats_numNodesAtDepth=1 1 6 45 329 2611 20224 159350 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 1000079 tree_stats_avgDegreeAtDepth=1 6 7.5 7.31111 7.93617 7.74569 7.87925 6.27599 tree_stats_height=8 tree_stats_numInternals=23217 tree_stats_numLeaves=159350 tree_stats_numNodes=182567 tree_stats_numKeys=1000079 tree_stats_avgDegreeInternal=7.86351 tree_stats_avgDegreeLeaves=6.27599 tree_stats_avgDegree=6.47787 tree_stats_avgKeyDepth=7 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=31187 first_thread_announced_epoch_by_thread=454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 452 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 452 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 452 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 454 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=30031022 sum_fasthtm_abort_total=65420874 sum_htmpostfix_commit_total=1559230 sum_htmpostfix_abort_total=5702496 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=49290216 sum_stmprefix_commit_total=97 sum_slow_commit_total=21014 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=10893 11104 10996 10757 10857 10671 11037 10588 10726 10701 10772 10648 10967 10814 11088 10990 10800 10736 10976 10856 10626 10552 10841 10489 10860 10963 10868 10614 10843 10598 10678 10552 10902 10715 10767 10466 10736 10893 10866 10852 10511 10572 10637 10714 10752 10680 10554 10541 11369 11666 11745 11491 11332 11138 11685 11951 11545 11583 11375 10982 11842 11777 11441 11434 11373 10769 11199 11629 11506 11458 11540 11074 11780 11587 11451 11307 10923 11451 11445 11754 11536 11639 11366 11370 11885 11754 11504 11182 11232 10893 11173 11552 11386 11447 11402 11069 11132 10847 10631 10571 10752 10720 10778 11085 11063 10781 10883 10377 11174 11121 10655 10677 10648 10064 10717 11078 10820 10711 10476 10568 10867 10892 10433 10568 10567 10659 10769 10785 10837 10940 10670 10389 10739 10968 10619 10416 10650 10201 10691 10993 10575 10453 10340 10519 sum_num_inserts_total=1579480 sum_num_deletes_by_thread=10687 11153 11013 10744 10820 10362 11094 10699 10817 10878 10783 10693 10993 10815 10856 10869 10608 10425 10938 10658 10737 10585 10791 10606 10967 10820 10686 10800 10971 10780 11034 10543 10739 10889 10813 10400 10723 10871 10778 10826 10251 10344 10654 10836 10633 10581 10686 10334 11477 11839 11454 11631 11326 11295 11767 11862 11519 11324 11315 10909 11784 11773 11480 11392 11577 10993 11396 11472 11641 11635 11406 11038 11729 11751 11285 11617 11128 11309 11552 11545 11572 11519 11369 11001 11840 11829 11513 11409 11248 10728 11293 11454 11360 11501 11443 11035 11139 10979 10568 10754 10653 10694 10912 11020 10951 10790 10888 10281 10790 11166 10569 10635 10803 10038 10721 11050 10714 10658 10502 10480 10893 10961 10573 10666 10539 10622 10853 10820 10830 11288 10834 10375 10837 11003 10687 10668 10717 10009 10625 10839 10401 10847 10667 10298 sum_num_deletes_total=1579525 sum_num_searches_by_thread=193489 200033 198175 194342 195376 190272 199783 190601 193447 193766 196221 191284 197440 198351 197912 198246 192008 189728 198621 193960 193597 192847 194537 187612 196634 198079 194098 196400 195609 192608 196968 189285 192516 192553 193042 187531 194503 196894 194744 194074 187493 191159 191108 197275 195559 191797 191648 188523 205756 212745 209485 210218 203962 201776 210423 214275 208184 204397 202982 195927 214033 211847 205339 202975 208606 197644 203881 205967 208073 206219 205830 198454 211589 212116 204140 202458 197885 204842 206002 210807 205797 206714 204404 202815 215398 212875 206873 203748 202415 195215 201352 208256 207145 206019 204248 200491 201980 195529 190158 191686 189881 194264 193456 199258 198859 194142 197572 182817 198044 199274 191260 190778 193261 182484 195460 198892 194141 193147 190052 189367 193932 195318 186681 191069 192016 192960 194247 193746 194193 197926 191878 187612 194781 198365 192629 189854 195873 180342 190637 198152 186652 194810 192512 186061 sum_num_searches_total=28452358 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=215069 222290 220184 215843 217053 211305 221914 211888 214990 215345 217776 212625 219400 219980 219856 220105 213416 210889 220535 215474 214960 213984 216169 208707 218461 219862 215652 217814 217423 213986 218680 210380 214157 214157 214622 208397 215962 218658 216388 215752 208255 212075 212399 218825 216944 213058 212888 209398 228602 236250 232684 233340 226620 224209 233875 238088 231248 227304 225672 217818 237659 235397 228260 225801 231556 219406 226476 229068 231220 229312 228776 220566 235098 235454 226876 225382 219936 227602 228999 234106 228905 229872 227139 225186 239123 236458 229890 226339 224895 216836 223818 231262 229891 228967 227093 222595 224251 217355 211357 213011 211286 215678 215146 221363 220873 215713 219343 203475 220008 221561 212484 212090 214712 202586 216898 221020 215675 214516 211030 210415 215692 217171 207687 212303 213122 214241 215869 215351 215860 220154 213382 208376 216357 220336 213935 210938 217240 200552 211953 219984 207628 216110 213519 206878 average_num_operations_total=219523 stdev_num_operations_total=674 sum_num_operations_total=31611363 min_num_operations_total=200552 max_num_operations_total=239123 first_time_thread_terminate_by_thread=10000887 10000502 10000238 10000272 10000241 10000685 10000755 10000246 10000710 10000260 10000903 10000262 10000667 10000705 10000923 10000586 10000943 10000779 10000904 10000258 10000898 10000263 10000921 10000912 10000488 10000246 10000597 10000596 10000773 10000589 10000590 10000470 10000681 10000578 10000897 10000808 10000256 10000891 10000668 10000666 10000926 10000554 10000758 10000730 10000806 10000915 10000755 10000899 10000860 10000234 10000615 10000616 10000909 10000845 10000603 10000666 10000234 10000814 10000524 10000618 10000393 10000238 10000260 10000498 10000478 10000468 10000381 10000473 10000900 10000487 10000551 10000411 10000884 10000855 10000471 10000248 10000777 10000648 10000936 10000780 10000700 10000683 10000534 10000816 10000577 10000403 10000687 10000639 10000863 10000635 10000859 10000601 10000900 10000236 10000735 10000251 10000959 10000850 10000479 10000869 10000700 10000237 10000237 10000694 10000851 10000238 10000730 10000787 10000881 10000546 10000868 10000690 10000527 10000878 10000896 10000718 10000236 10000604 10000927 10000636 10001891 10000674 10000754 10000932 10000876 10000778 10000712 10000704 10000808 10000608 10000917 10000573 10000373 10000758 10000749 10000275 10000874 10000669 10000390 10000920 10000764 10000241 10000877 10000807 min_time_thread_terminate_total=10000234 max_time_thread_terminate_total=10001891 first_time_thread_start_by_thread=8 4 4 4 4 4 4 5 5 4 4 4 4 4 4 5 5 4 4 4 5 5 4 4 4 4 4 4 4 4 4 5 5 4 4 4 4 4 4 5 5 4 4 4 5 5 4 4 5 5 5 5 5 6 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 min_time_thread_start_total=4 max_time_thread_start_total=8 sum_duration_all_ops_total= threads_final_keysum=1000280916884 threads_final_size=1000079 final_keysum=1000280916884 final_size=1000079 validate_result=success Validation OK. Structural validation OK. total_find=28452358 total_rq=0 total_inserts=1579480 total_deletes=1579525 total_updates=3159005 total_queries=28452358 total_ops=31611363 find_throughput=2845235 rq_throughput=0 update_throughput=315900 query_throughput=2845235 total_throughput=3161136 total find : 28452358 total rq : 0 total inserts : 1579480 total deletes : 1579525 total updates : 3159005 total queries : 28452358 total ops : 31611363 find throughput : 2845235 rq throughput : 0 update throughput : 315900 query throughput : 2845235 total throughput : 3161136 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=32.688 PAPI_L3_TCM=8.95169 PAPI_TOT_CYC=114491 PAPI_TOT_INS=3614.92 garbage=14022235172355 total_execution_walltime=20.594s REALTIME_MAIN_END_PERF_FORMAT=6998638.920818867 RH noREC w/postfix htm system shutdown: HTM_ATTEMPT_THRESH=40 [time_cmd_output] time_elapsed_sec=20.75, faults_major=0, faults_minor=187842, mem_maxresident_kb=747404, user_cputime=2879.44, sys_cputime=36.75, percent_cpu=14050% 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=2