__step=000236 __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.rhnorec_post -nwork 96 -nprefill 96 -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=data000236.txt __path_data=data/data000236.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6997368.636135655 binary=./brown_int_bst_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_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=2000000 PREFILL_THREADS=96 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=96 WORK_THREADS=96 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,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 RH noREC w/postfix htm system ready Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.013s prefilling_size=225775 prefilling_elapsed_millis=1.001s prefilling_size=245129 prefilling_elapsed_millis=1.101s prefilling_size=264217 prefilling_elapsed_millis=1.201s prefilling_size=282800 prefilling_elapsed_millis=1.301s prefilling_size=300834 prefilling_elapsed_millis=1.401s prefilling_size=318236 prefilling_elapsed_millis=1.502s prefilling_size=335287 prefilling_elapsed_millis=1.602s prefilling_size=351803 prefilling_elapsed_millis=1.702s prefilling_size=367920 prefilling_elapsed_millis=1.802s prefilling_size=383726 prefilling_elapsed_millis=1.902s prefilling_size=399377 prefilling_elapsed_millis=2.003s prefilling_size=414431 prefilling_elapsed_millis=2.103s prefilling_size=428940 prefilling_elapsed_millis=2.203s prefilling_size=443213 prefilling_elapsed_millis=2.303s prefilling_size=457023 prefilling_elapsed_millis=2.403s prefilling_size=470627 prefilling_elapsed_millis=2.504s prefilling_size=483680 prefilling_elapsed_millis=2.604s prefilling_size=496297 prefilling_elapsed_millis=2.704s prefilling_size=508815 prefilling_elapsed_millis=2.804s prefilling_size=520914 prefilling_elapsed_millis=2.904s prefilling_size=533116 prefilling_elapsed_millis=3.005s prefilling_size=544554 prefilling_elapsed_millis=3.105s prefilling_size=555816 prefilling_elapsed_millis=3.205s prefilling_size=566788 prefilling_elapsed_millis=3.305s prefilling_size=577570 prefilling_elapsed_millis=3.405s prefilling_size=587882 prefilling_elapsed_millis=3.506s prefilling_size=598118 prefilling_elapsed_millis=3.606s prefilling_size=608117 prefilling_elapsed_millis=3.706s prefilling_size=617548 prefilling_elapsed_millis=3.806s prefilling_size=626856 prefilling_elapsed_millis=3.906s prefilling_size=636167 prefilling_elapsed_millis=4.007s prefilling_size=645113 prefilling_elapsed_millis=4.107s prefilling_size=654073 prefilling_elapsed_millis=4.207s prefilling_size=663015 prefilling_elapsed_millis=4.307s prefilling_size=671261 prefilling_elapsed_millis=4.407s prefilling_size=679207 prefilling_elapsed_millis=4.508s prefilling_size=687239 prefilling_elapsed_millis=4.608s prefilling_size=694927 prefilling_elapsed_millis=4.708s prefilling_size=702360 prefilling_elapsed_millis=4.808s prefilling_size=709554 prefilling_elapsed_millis=4.908s prefilling_size=716732 prefilling_elapsed_millis=5.009s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 283209 keys with 96 threads... tid=0 thread_prefill_amount=8332 percent_done_estimate=282.4 elapsed_s=6 est_remaining_s=-8 / -2 timing_elapsed 1.07528s prefilling_size=1000000 prefilling_elapsed_millis=1.077s finished prefilling to size 1000000 for expected size 1000000 keysum=999507815816, performing 3053140 updates; total_prefilling_elapsed_ms=1077 ms) pref_size=1000000 pref_millis=1077 prefill_elapsed_ms=1078 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=40 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6997375.56154723 REALTIME_END_PERF_FORMAT=6997385.56311425 ############################################################################### ################################## 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 (96 threads)... partitioned into 479 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.015s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 63 125 245 463 848 1529 2654 4366 6907 10387 14925 20650 27127 34009 40552 46463 51104 54102 54936 53092 48780 42995 36155 29282 22759 17008 12344 8471 5426 3381 1960 1126 594 282 140 59 21 4 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 1 0 3 16 45 91 229 500 951 1913 3159 4919 7573 10725 14597 18362 22175 25364 28000 29990 30168 28497 26085 22455 18620 14928 11175 8382 5976 3844 2463 1435 826 466 211 111 48 19 4 1 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 125 248 479 893 1620 2883 4866 7858 12300 18084 25569 34700 44734 55149 64825 73279 79466 82936 83082 78948 71492 62240 51737 41379 31936 23519 16853 11402 7225 4423 2561 1420 748 351 170 69 23 5 1 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 125 248 479 893 1620 2883 4866 7858 12300 18084 25569 34700 44734 55149 64825 73279 79466 82936 83082 78948 71492 62240 51737 41379 31936 23519 16853 11402 7225 4423 2561 1420 748 351 170 69 23 5 1 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.96875 1.984 1.94355 1.8977 1.8645 1.8358 1.76726 1.71763 1.68631 1.62577 1.58859 1.54949 1.50741 1.47257 1.44013 1.41367 1.38704 1.36285 1.33937 1.31121 1.28768 1.26919 1.25035 1.23382 1.22178 1.20388 1.19172 1.17392 1.15778 1.14422 1.13588 1.1148 1.10845 1.09225 1.08547 1.05882 1.02899 1.04348 1 1 tree_stats_height=46 tree_stats_numInternals=655398 tree_stats_numLeaves=344327 tree_stats_numNodes=999725 tree_stats_numKeys=999725 tree_stats_avgDegreeInternal=1.52537 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34442 tree_stats_avgKeyDepth=24.3729 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=780 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:32 2:39 4:497 8:29983 16:40278 32:7 [2^00, 2^01]: 32 (2^01, 2^02]: 39 (2^02, 2^03]: 497 (2^03, 2^04]: 29983 (2^04, 2^05]: 40278 (2^05, 2^06]: 7 sum_limbo_reclamation_event_size_total=1149411 count_limbo_reclamation_event_size_by_thread=738 738 738 738 737 738 738 738 737 738 738 738 738 738 738 738 738 738 738 738 737 738 738 738 738 738 737 738 738 738 738 738 738 738 738 738 737 738 737 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 738 737 738 738 738 738 737 737 738 738 738 738 738 738 738 738 738 737 738 738 738 738 738 738 737 738 738 738 738 737 738 count_limbo_reclamation_event_size_total=70836 sum_limbo_reclamation_event_count_total=71126 first_thread_announced_epoch_by_thread=1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 1560 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=43912659 sum_fasthtm_abort_total=96694963 sum_htmpostfix_commit_total=2284220 sum_htmpostfix_abort_total=2368059 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=47339438 sum_stmprefix_commit_total=2069 sum_slow_commit_total=19170 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=23937 23562 23816 24352 20352 25337 23982 24142 24738 23965 20703 25407 24052 23683 24698 24178 20629 25762 24171 24288 23935 24135 20411 25403 24303 24671 23882 23922 20346 24920 24532 24247 24352 24615 20995 26072 24296 23974 24461 24610 20802 25357 24259 23979 23909 23980 20706 25894 25713 25142 25228 25136 20300 20191 25667 24981 25035 25260 20596 26669 25543 25540 25061 25102 20055 26155 24820 24954 25726 24856 20582 26477 25063 24790 24993 25084 20380 20300 25173 25336 25910 25184 20480 26415 25442 25025 25148 25149 19732 26227 24829 24630 25209 24735 20515 26162 sum_num_inserts_total=2309422 sum_num_deletes_by_thread=23622 23482 24118 24399 20464 24931 24426 23954 24539 23878 20842 25565 24127 24039 24248 23843 20733 25500 24099 24027 24244 24323 20429 25248 24128 24292 23731 23898 20388 25076 24230 24515 24447 24909 20808 26106 23864 23906 24636 24651 20703 25531 24048 24166 24393 24293 20319 26014 25702 25105 25265 25206 20364 20277 25374 24894 25578 25045 20274 26815 25455 25485 25147 25032 20207 26121 24667 24727 25617 24882 20486 26283 25260 24931 25357 25258 20584 20238 25264 24794 25703 25169 20377 26772 25521 25015 24888 25057 19839 26108 24814 24578 25480 24884 20756 26225 sum_num_deletes_total=2309012 sum_num_searches_by_thread=425996 421894 431147 441511 365894 451226 434147 434819 443654 433544 377346 461221 434641 427283 440441 435163 373005 464823 436446 438543 437341 439481 368548 456217 437604 440445 428006 431991 366612 448301 438443 436873 440361 444990 377174 470863 433075 430131 437616 442216 373698 453655 436321 432009 431419 432286 370148 465960 460414 452908 455830 455786 364303 367211 461302 450828 451530 456561 368863 482522 461006 457349 452128 451954 363643 470276 450098 445994 459672 445368 371226 471113 452844 451668 454980 454010 369140 364895 453536 451607 465052 453130 367134 477153 462147 450424 449943 450837 355381 470624 449904 444393 457144 448430 371451 471444 sum_num_searches_total=41599684 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=473555 468938 479081 490262 406710 501494 482555 482915 492931 481387 418891 512193 482820 475005 489387 483184 414367 516085 484716 486858 485520 487939 409388 506868 486035 489408 475619 479811 407346 498297 487205 485635 489160 494514 418977 523041 481235 478011 486713 491477 415203 504543 484628 480154 479721 480559 411173 517868 511829 503155 506323 506128 404967 407679 512343 500703 502143 506866 409733 536006 512004 508374 502336 502088 403905 522552 499585 495675 511015 495106 412294 523873 503167 501389 505330 504352 410104 405433 503973 501737 516665 503483 407991 530340 513110 500464 499979 501043 394952 522959 499547 493601 507833 498049 412722 523831 average_num_operations_total=481438 stdev_num_operations_total=3817 sum_num_operations_total=46218118 min_num_operations_total=394952 max_num_operations_total=536006 first_time_thread_terminate_by_thread=10000370 10000390 10000343 10000155 10000394 10000155 10000398 10000342 10000403 10000157 10000389 10000188 10000218 10000402 10000302 10000377 10000226 10000359 10000221 10000391 10000384 10000157 10000155 10000239 10000353 10000209 10000189 10000311 10000369 10000331 10000218 10000334 10000401 10000351 10000272 10000395 10000316 10000288 10000155 10000396 10000309 10000163 10000336 10000397 10000388 10000157 10000386 10000264 10000373 10000312 10000355 10000368 10000378 10000301 10000199 10000242 10000298 10000376 10000321 10000205 10000154 10000381 10000329 10000154 10000370 10000364 10000235 10000211 10000205 10000274 10000301 10000393 10000343 10000183 10000179 10000343 10000157 10000399 10000195 10000380 10000178 10000155 10000301 10000280 10000273 10000177 10000154 10000158 10000319 10000246 10000227 10000248 10000234 10000266 10000153 10000179 min_time_thread_terminate_total=10000153 max_time_thread_terminate_total=10000403 first_time_thread_start_by_thread=9 6 5 5 5 5 6 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 4 6 5 5 5 5 6 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 6 6 6 6 6 5 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 5 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 min_time_thread_start_total=4 max_time_thread_start_total=9 sum_duration_all_ops_total= threads_final_keysum=999273270849 threads_final_size=999725 final_keysum=999273270849 final_size=999725 validate_result=success Validation OK. Structural validation OK. total_find=41599684 total_rq=0 total_inserts=2309422 total_deletes=2309012 total_updates=4618434 total_queries=41599684 total_ops=46218118 find_throughput=4159968 rq_throughput=0 update_throughput=461843 query_throughput=4159968 total_throughput=4621811 total find : 41599684 total rq : 0 total inserts : 2309422 total deletes : 2309012 total updates : 4618434 total queries : 41599684 total ops : 46218118 find throughput : 4159968 rq throughput : 0 update throughput : 461843 query throughput : 4159968 total throughput : 4621811 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=27.2774 PAPI_L3_TCM=6.2418 PAPI_TOT_CYC=53048.6 PAPI_TOT_INS=3791.25 garbage=20790439192107 total_execution_walltime=16.553s REALTIME_MAIN_END_PERF_FORMAT=6997385.190665749 RH noREC w/postfix htm system shutdown: HTM_ATTEMPT_THRESH=40 [time_cmd_output] time_elapsed_sec=16.70, faults_major=0, faults_minor=114925, mem_maxresident_kb=458244, user_cputime=1541.73, sys_cputime=22.15, percent_cpu=9359% timeout=false algorithm=brown_int_bst_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