__step=000017
__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 48 -nprefill 48 -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=data000017.txt
__path_data=data/data000017.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6991714.449806468
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=20000000
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=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
RH noREC w/postfix htm system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.022s
prefilling_size=464604 prefilling_elapsed_millis=1.001s
prefilling_size=508530 prefilling_elapsed_millis=1.101s
prefilling_size=552205 prefilling_elapsed_millis=1.201s
prefilling_size=595814 prefilling_elapsed_millis=1.301s
prefilling_size=638787 prefilling_elapsed_millis=1.402s
prefilling_size=681751 prefilling_elapsed_millis=1.502s
prefilling_size=724284 prefilling_elapsed_millis=1.602s
prefilling_size=766484 prefilling_elapsed_millis=1.702s
prefilling_size=808852 prefilling_elapsed_millis=1.802s
prefilling_size=850910 prefilling_elapsed_millis=1.903s
prefilling_size=892699 prefilling_elapsed_millis=2.003s
prefilling_size=934238 prefilling_elapsed_millis=2.103s
prefilling_size=975489 prefilling_elapsed_millis=2.203s
prefilling_size=1016789 prefilling_elapsed_millis=2.303s
prefilling_size=1057669 prefilling_elapsed_millis=2.404s
prefilling_size=1098405 prefilling_elapsed_millis=2.504s
prefilling_size=1138943 prefilling_elapsed_millis=2.604s
prefilling_size=1179349 prefilling_elapsed_millis=2.704s
prefilling_size=1219176 prefilling_elapsed_millis=2.804s
prefilling_size=1258748 prefilling_elapsed_millis=2.905s
prefilling_size=1298414 prefilling_elapsed_millis=3.005s
prefilling_size=1337845 prefilling_elapsed_millis=3.105s
prefilling_size=1376839 prefilling_elapsed_millis=3.205s
prefilling_size=1415848 prefilling_elapsed_millis=3.305s
prefilling_size=1454792 prefilling_elapsed_millis=3.405s
prefilling_size=1493608 prefilling_elapsed_millis=3.506s
prefilling_size=1532076 prefilling_elapsed_millis=3.606s
prefilling_size=1570451 prefilling_elapsed_millis=3.706s
prefilling_size=1608676 prefilling_elapsed_millis=3.806s
prefilling_size=1646572 prefilling_elapsed_millis=3.906s
prefilling_size=1684090 prefilling_elapsed_millis=4.007s
prefilling_size=1721386 prefilling_elapsed_millis=4.107s
prefilling_size=1759071 prefilling_elapsed_millis=4.207s
prefilling_size=1796228 prefilling_elapsed_millis=4.307s
prefilling_size=1833267 prefilling_elapsed_millis=4.407s
prefilling_size=1870096 prefilling_elapsed_millis=4.508s
prefilling_size=1906882 prefilling_elapsed_millis=4.608s
prefilling_size=1943408 prefilling_elapsed_millis=4.708s
prefilling_size=1979878 prefilling_elapsed_millis=4.808s
prefilling_size=2016264 prefilling_elapsed_millis=4.908s
prefilling_size=2052114 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 7947830 keys with 48 threads...
tid=0 thread_prefill_amount=45826 percent_done_estimate=27.7 elapsed_s=5 est_remaining_s=32 / 37
tid=0 thread_prefill_amount=49992 percent_done_estimate=30.2 elapsed_s=6 est_remaining_s=30 / 36
tid=0 thread_prefill_amount=54158 percent_done_estimate=32.7 elapsed_s=6 est_remaining_s=29 / 35
tid=0 thread_prefill_amount=58324 percent_done_estimate=35.2 elapsed_s=7 est_remaining_s=28 / 34
tid=0 thread_prefill_amount=62490 percent_done_estimate=37.7 elapsed_s=7 est_remaining_s=26 / 34
tid=0 thread_prefill_amount=66656 percent_done_estimate=40.3 elapsed_s=8 est_remaining_s=25 / 33
tid=0 thread_prefill_amount=70822 percent_done_estimate=42.8 elapsed_s=8 est_remaining_s=24 / 32
tid=0 thread_prefill_amount=74988 percent_done_estimate=45.3 elapsed_s=8 est_remaining_s=23 / 31
tid=0 thread_prefill_amount=79154 percent_done_estimate=47.8 elapsed_s=9 est_remaining_s=22 / 31
tid=0 thread_prefill_amount=83320 percent_done_estimate=50.3 elapsed_s=9 est_remaining_s=21 / 30
tid=0 thread_prefill_amount=87486 percent_done_estimate=52.8 elapsed_s=10 est_remaining_s=20 / 29
tid=0 thread_prefill_amount=91652 percent_done_estimate=55.4 elapsed_s=10 est_remaining_s=18 / 29
tid=0 thread_prefill_amount=95818 percent_done_estimate=57.9 elapsed_s=11 est_remaining_s=17 / 28
tid=0 thread_prefill_amount=99984 percent_done_estimate=60.4 elapsed_s=11 est_remaining_s=16 / 27
tid=0 thread_prefill_amount=104150 percent_done_estimate=62.9 elapsed_s=11 est_remaining_s=15 / 27
tid=0 thread_prefill_amount=108316 percent_done_estimate=65.4 elapsed_s=12 est_remaining_s=14 / 26
tid=0 thread_prefill_amount=112482 percent_done_estimate=67.9 elapsed_s=12 est_remaining_s=13 / 25
tid=0 thread_prefill_amount=116648 percent_done_estimate=70.4 elapsed_s=13 est_remaining_s=12 / 25
tid=0 thread_prefill_amount=120814 percent_done_estimate=73.0 elapsed_s=13 est_remaining_s=11 / 24
tid=0 thread_prefill_amount=124980 percent_done_estimate=75.5 elapsed_s=14 est_remaining_s=10 / 24
tid=0 thread_prefill_amount=129146 percent_done_estimate=78.0 elapsed_s=14 est_remaining_s=9 / 23
tid=0 thread_prefill_amount=133312 percent_done_estimate=80.5 elapsed_s=15 est_remaining_s=8 / 23
tid=0 thread_prefill_amount=137478 percent_done_estimate=83.0 elapsed_s=15 est_remaining_s=7 / 22
tid=0 thread_prefill_amount=141644 percent_done_estimate=85.5 elapsed_s=15 est_remaining_s=6 / 21
tid=0 thread_prefill_amount=145810 percent_done_estimate=88.1 elapsed_s=16 est_remaining_s=5 / 21
tid=0 thread_prefill_amount=149976 percent_done_estimate=90.6 elapsed_s=16 est_remaining_s=4 / 20
tid=0 thread_prefill_amount=154142 percent_done_estimate=93.1 elapsed_s=17 est_remaining_s=3 / 20
tid=0 thread_prefill_amount=158308 percent_done_estimate=95.6 elapsed_s=17 est_remaining_s=2 / 19
tid=0 thread_prefill_amount=162474 percent_done_estimate=98.1 elapsed_s=18 est_remaining_s=1 / 18
tid=0 thread_prefill_amount=166640 percent_done_estimate=100.6 elapsed_s=18 est_remaining_s=-0 / 18
tid=0 thread_prefill_amount=170806 percent_done_estimate=103.2 elapsed_s=19 est_remaining_s=-1 / 17
tid=0 thread_prefill_amount=174972 percent_done_estimate=105.7 elapsed_s=19 est_remaining_s=-2 / 17
tid=0 thread_prefill_amount=179138 percent_done_estimate=108.2 elapsed_s=19 est_remaining_s=-3 / 16
tid=0 thread_prefill_amount=183304 percent_done_estimate=110.7 elapsed_s=20 est_remaining_s=-4 / 16
tid=0 thread_prefill_amount=187470 percent_done_estimate=113.2 elapsed_s=20 est_remaining_s=-5 / 15
tid=0 thread_prefill_amount=191636 percent_done_estimate=115.7 elapsed_s=21 est_remaining_s=-6 / 14
tid=0 thread_prefill_amount=195802 percent_done_estimate=118.3 elapsed_s=21 est_remaining_s=-7 / 14
tid=0 thread_prefill_amount=199968 percent_done_estimate=120.8 elapsed_s=22 est_remaining_s=-8 / 13
tid=0 thread_prefill_amount=204134 percent_done_estimate=123.3 elapsed_s=22 est_remaining_s=-9 / 13
tid=0 thread_prefill_amount=208300 percent_done_estimate=125.8 elapsed_s=23 est_remaining_s=-10 / 12
timing_elapsed 17.6277s
prefilling_size=10000000 prefilling_elapsed_millis=17.67s
finished prefilling to size 10000000 for expected size 10000000 keysum=99999739216717, performing 16290822 updates; total_prefilling_elapsed_ms=17670 ms)
pref_size=10000000
pref_millis=17670
prefill_elapsed_ms=17670
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=866
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6991737.432154677
REALTIME_END_PERF_FORMAT=6991747.432228361
###############################################################################
################################## 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 (48 threads)...
partitioned into 256 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.076s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 503 981 1884 3559 6531 11600 19948 32992 52344 79894 116664 164127 220907 284628 351753 416189 471717 511557 530848 527959 503668 461187 406700 345649 283252 224210 171400 126574 90155 62386 41942 27241 17325 10711 6475 3742 2199 1172 606 325 177 94 33 11 3 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 3 11 43 106 318 801 1723 3649 7248 13014 22647 36209 56230 81852 113678 149446 187854 224413 255076 276142 285106 282051 265426 241271 210058 175898 142219 111080 83693 60414 42276 29007 18944 12120 7615 4758 2709 1655 902 457 243 131 81 25 9 2 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 506 992 1927 3665 6849 12401 21671 36641 59592 92908 139311 200336 277137 366480 465431 565635 659571 735970 785924 804101 788774 743238 672126 586920 493310 400108 313619 237654 173848 122800 84218 56248 36269 22831 14090 8500 4908 2827 1508 782 420 225 114 36 12 3 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 506 992 1927 3665 6849 12401 21671 36641 59592 92908 139311 200336 277137 366480 465431 565635 659571 735970 785924 804101 788774 743238 672126 586920 493310 400108 313619 237654 173848 122800 84218 56248 36269 22831 14090 8500 4908 2827 1508 782 420 225 114 36 12 3 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.97656 1.9664 1.95363 1.92423 1.89768 1.85706 1.81211 1.77029 1.72596 1.6807 1.63953 1.60061 1.5641 1.52527 1.49335 1.45954 1.43028 1.40064 1.3728 1.34768 1.32436 1.30372 1.28381 1.26813 1.25159 1.23688 1.22346 1.21126 1.19892 1.18778 1.17779 1.16987 1.1605 1.15181 1.148 1.14372 1.13718 1.12795 1.11885 1.11671 1.12148 1.11429 1.08889 1.02632 1.02778 1 1 1
tree_stats_height=56
tree_stats_numInternals=6594334
tree_stats_numLeaves=3408614
tree_stats_numNodes=10002948
tree_stats_numKeys=10002948
tree_stats_avgDegreeInternal=1.5169
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34076
tree_stats_avgKeyDepth=28.2797
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=10658
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:68 2:1513 4:52009 8:361890 16:54361 32:29 64:42
[2^00, 2^01]: 68
(2^01, 2^02]: 1513
(2^02, 2^03]: 52009
(2^03, 2^04]: 361890
(2^04, 2^05]: 54361
(2^05, 2^06]: 29
(2^06, 2^07]: 42
sum_limbo_reclamation_event_size_total=5381354
count_limbo_reclamation_event_size_by_thread=9790 9790 9790 9789 9790 9790 9789 9790 9790 9790 9790 9790 9790 9790 9790 9790 9790 9790 9789 9790 9790 9790 9790 9790 9790 9790 9790 9789 9790 9790 9790 9790 9790 9789 9790 9789 9790 9789 9790 9789 9790 9790 9790 9790 9790 9790 9790 9790
count_limbo_reclamation_event_size_total=469912
sum_limbo_reclamation_event_count_total=470063
first_thread_announced_epoch_by_thread=21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316 21316
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=215310923
sum_fasthtm_abort_total=16884236
sum_htmpostfix_commit_total=835
sum_htmpostfix_abort_total=14784
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=994
sum_stmprefix_commit_total=10
sum_slow_commit_total=369
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=221104 224001 223350 225867 224637 220660 222949 224792 226782 226350 225826 224123 221376 223291 226338 227286 226313 224103 222672 221755 225122 226157 224415 223590 221036 223840 224700 225708 225301 221812 220522 223951 227044 226183 225163 224057 223550 224712 226160 226745 225203 222630 222778 223048 224944 226085 224414 224431
sum_num_inserts_total=10766876
sum_num_deletes_by_thread=221659 223685 223718 226617 225005 220224 222666 224302 225465 226891 224575 223468 220828 223624 226422 226964 225502 224123 224028 222177 225157 225610 224837 223732 222003 224306 224540 226315 224809 220971 220488 222927 227603 226052 225023 224360 222910 224651 225583 226490 225310 223351 223082 224130 224567 226100 225077 223970
sum_num_deletes_total=10765897
sum_num_searches_by_thread=3991031 4024971 4030082 4073154 4038024 3966058 4017475 4037395 4069402 4077466 4049272 4032284 3989773 4013468 4074746 4082672 4064702 4036557 4007150 3991659 4056665 4066448 4038679 4028885 3988903 4027716 4043045 4067782 4048363 3996009 3969816 4024117 4084545 4068913 4052353 4041177 4023069 4050360 4065619 4073181 4054579 4015821 4013938 4023324 4048801 4072103 4039368 4028444
sum_num_searches_total=193779364
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4433794 4472657 4477150 4525638 4487666 4406942 4463090 4486489 4521649 4530707 4499673 4479875 4431977 4460383 4527506 4536922 4516517 4484783 4453850 4435591 4506944 4518215 4487931 4476207 4431942 4475862 4492285 4519805 4498473 4438792 4410826 4470995 4539192 4521148 4502539 4489594 4469529 4499723 4517362 4526416 4505092 4461802 4459798 4470502 4498312 4524288 4488859 4476845
average_num_operations_total=4485669
stdev_num_operations_total=4864
sum_num_operations_total=215312137
min_num_operations_total=4406942
max_num_operations_total=4539192
first_time_thread_terminate_by_thread=10000127 10000073 10000074 10000073 10000073 10000073 10000073 10000074 10000073 10000073 10000073 10000074 10000074 10000073 10000073 10000074 10000074 10000074 10000074 10000075 10000073 10000074 10000073 10000074 10000074 10000073 10000075 10000073 10000073 10000073 10000073 10000074 10000073 10000074 10000073 10000074 10000073 10000073 10000073 10000073 10000074 10000074 10000074 10000073 10000073 10000074 10000073 10000074
min_time_thread_terminate_total=10000073
max_time_thread_terminate_total=10000127
first_time_thread_start_by_thread=9 5 5 4 5 5 4 4 5 5 5 5 5 5 5 5 5 4 5 5 4 5 5 5 4 5 5 4 5 5 4 4 5 5 5 5 5 5 5 5 5 4 5 5 4 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=
threads_final_keysum=100033196440485
threads_final_size=10002948
final_keysum=100033196440485
final_size=10002948
validate_result=success
Validation OK.
Structural validation OK.
total_find=193779364
total_rq=0
total_inserts=10766876
total_deletes=10765897
total_updates=21532773
total_queries=193779364
total_ops=215312137
find_throughput=19377936
rq_throughput=0
update_throughput=2153277
query_throughput=19377936
total_throughput=21531213
total find : 193779364
total rq : 0
total inserts : 10766876
total deletes : 10765897
total updates : 21532773
total queries : 193779364
total ops : 215312137
find throughput : 19377936
rq throughput : 0
update throughput : 2153277
query throughput : 19377936
total throughput : 21531213
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=21.613
PAPI_L3_TCM=7.11434
PAPI_TOT_CYC=6216.62
PAPI_TOT_INS=1347.33
garbage=968887335906252
total_execution_walltime=33.229s
REALTIME_MAIN_END_PERF_FORMAT=6991747.679927397
RH noREC w/postfix htm system shutdown:
HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=33.40, faults_major=0, faults_minor=253905, mem_maxresident_kb=1016232, user_cputime=1538.93, sys_cputime=39.96, percent_cpu=4726%
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=1