__step=000009
__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.hybridnorec -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=data000009.txt
__path_data=data/data000009.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6991392.928786325
binary=./brown_int_bst_tm_auto.debra.hybridnorec
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
Hybrid noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.008s
prefilling_size=799569 prefilling_elapsed_millis=1.001s
prefilling_size=876009 prefilling_elapsed_millis=1.101s
prefilling_size=951720 prefilling_elapsed_millis=1.201s
prefilling_size=1026694 prefilling_elapsed_millis=1.301s
prefilling_size=1101061 prefilling_elapsed_millis=1.402s
prefilling_size=1174764 prefilling_elapsed_millis=1.502s
prefilling_size=1247764 prefilling_elapsed_millis=1.602s
prefilling_size=1320137 prefilling_elapsed_millis=1.702s
prefilling_size=1392124 prefilling_elapsed_millis=1.802s
prefilling_size=1463439 prefilling_elapsed_millis=1.903s
prefilling_size=1534376 prefilling_elapsed_millis=2.003s
prefilling_size=1604749 prefilling_elapsed_millis=2.103s
prefilling_size=1674667 prefilling_elapsed_millis=2.203s
prefilling_size=1743963 prefilling_elapsed_millis=2.303s
prefilling_size=1812980 prefilling_elapsed_millis=2.404s
prefilling_size=1881094 prefilling_elapsed_millis=2.504s
prefilling_size=1948785 prefilling_elapsed_millis=2.604s
prefilling_size=2015965 prefilling_elapsed_millis=2.704s
prefilling_size=2082037 prefilling_elapsed_millis=2.804s
prefilling_size=2147882 prefilling_elapsed_millis=2.905s
prefilling_size=2213338 prefilling_elapsed_millis=3.005s
prefilling_size=2278112 prefilling_elapsed_millis=3.105s
prefilling_size=2342288 prefilling_elapsed_millis=3.205s
prefilling_size=2405848 prefilling_elapsed_millis=3.305s
prefilling_size=2469539 prefilling_elapsed_millis=3.405s
prefilling_size=2532505 prefilling_elapsed_millis=3.506s
prefilling_size=2594559 prefilling_elapsed_millis=3.606s
prefilling_size=2656159 prefilling_elapsed_millis=3.706s
prefilling_size=2717180 prefilling_elapsed_millis=3.806s
prefilling_size=2777553 prefilling_elapsed_millis=3.906s
prefilling_size=2837983 prefilling_elapsed_millis=4.007s
prefilling_size=2897672 prefilling_elapsed_millis=4.107s
prefilling_size=2956558 prefilling_elapsed_millis=4.207s
prefilling_size=3015515 prefilling_elapsed_millis=4.307s
prefilling_size=3073834 prefilling_elapsed_millis=4.407s
prefilling_size=3131534 prefilling_elapsed_millis=4.508s
prefilling_size=3189178 prefilling_elapsed_millis=4.608s
prefilling_size=3246133 prefilling_elapsed_millis=4.708s
prefilling_size=3303040 prefilling_elapsed_millis=4.808s
prefilling_size=3359040 prefilling_elapsed_millis=4.908s
prefilling_size=3414608 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 6585318 keys with 48 threads...
tid=0 thread_prefill_amount=74988 percent_done_estimate=54.7 elapsed_s=5 est_remaining_s=10 / 15
tid=0 thread_prefill_amount=79154 percent_done_estimate=57.7 elapsed_s=5 est_remaining_s=9 / 15
tid=0 thread_prefill_amount=83320 percent_done_estimate=60.7 elapsed_s=6 est_remaining_s=8 / 14
tid=0 thread_prefill_amount=87486 percent_done_estimate=63.8 elapsed_s=6 est_remaining_s=8 / 14
tid=0 thread_prefill_amount=91652 percent_done_estimate=66.8 elapsed_s=6 est_remaining_s=7 / 13
tid=0 thread_prefill_amount=95818 percent_done_estimate=69.8 elapsed_s=7 est_remaining_s=6 / 13
tid=0 thread_prefill_amount=99984 percent_done_estimate=72.9 elapsed_s=7 est_remaining_s=6 / 12
tid=0 thread_prefill_amount=104150 percent_done_estimate=75.9 elapsed_s=7 est_remaining_s=5 / 12
tid=0 thread_prefill_amount=108316 percent_done_estimate=79.0 elapsed_s=7 est_remaining_s=4 / 12
tid=0 thread_prefill_amount=112482 percent_done_estimate=82.0 elapsed_s=8 est_remaining_s=4 / 11
tid=0 thread_prefill_amount=116648 percent_done_estimate=85.0 elapsed_s=8 est_remaining_s=3 / 11
tid=0 thread_prefill_amount=120814 percent_done_estimate=88.1 elapsed_s=8 est_remaining_s=2 / 11
tid=0 thread_prefill_amount=124980 percent_done_estimate=91.1 elapsed_s=8 est_remaining_s=2 / 10
tid=0 thread_prefill_amount=129146 percent_done_estimate=94.1 elapsed_s=9 est_remaining_s=1 / 10
tid=0 thread_prefill_amount=133312 percent_done_estimate=97.2 elapsed_s=9 est_remaining_s=1 / 9
tid=0 thread_prefill_amount=137478 percent_done_estimate=100.2 elapsed_s=9 est_remaining_s=-0 / 9
tid=0 thread_prefill_amount=141644 percent_done_estimate=103.2 elapsed_s=9 est_remaining_s=-1 / 9
tid=0 thread_prefill_amount=145810 percent_done_estimate=106.3 elapsed_s=10 est_remaining_s=-1 / 8
tid=0 thread_prefill_amount=149976 percent_done_estimate=109.3 elapsed_s=10 est_remaining_s=-2 / 8
tid=0 thread_prefill_amount=154142 percent_done_estimate=112.4 elapsed_s=10 est_remaining_s=-3 / 8
tid=0 thread_prefill_amount=158308 percent_done_estimate=115.4 elapsed_s=10 est_remaining_s=-3 / 7
tid=0 thread_prefill_amount=162474 percent_done_estimate=118.4 elapsed_s=11 est_remaining_s=-4 / 7
tid=0 thread_prefill_amount=166640 percent_done_estimate=121.5 elapsed_s=11 est_remaining_s=-4 / 7
tid=0 thread_prefill_amount=170806 percent_done_estimate=124.5 elapsed_s=11 est_remaining_s=-5 / 6
tid=0 thread_prefill_amount=174972 percent_done_estimate=127.5 elapsed_s=11 est_remaining_s=-6 / 6
tid=0 thread_prefill_amount=179138 percent_done_estimate=130.6 elapsed_s=12 est_remaining_s=-6 / 6
tid=0 thread_prefill_amount=183304 percent_done_estimate=133.6 elapsed_s=12 est_remaining_s=-7 / 5
tid=0 thread_prefill_amount=187470 percent_done_estimate=136.6 elapsed_s=12 est_remaining_s=-7 / 5
tid=0 thread_prefill_amount=191636 percent_done_estimate=139.7 elapsed_s=12 est_remaining_s=-8 / 4
tid=0 thread_prefill_amount=195802 percent_done_estimate=142.7 elapsed_s=13 est_remaining_s=-9 / 4
tid=0 thread_prefill_amount=199968 percent_done_estimate=145.8 elapsed_s=13 est_remaining_s=-9 / 4
tid=0 thread_prefill_amount=204134 percent_done_estimate=148.8 elapsed_s=13 est_remaining_s=-10 / 3
tid=0 thread_prefill_amount=208300 percent_done_estimate=151.8 elapsed_s=13 est_remaining_s=-10 / 3
timing_elapsed 7.83376s
prefilling_size=10000000 prefilling_elapsed_millis=7.852s
finished prefilling to size 10000000 for expected size 10000000 keysum=99994081978960, performing 18468106 updates; total_prefilling_elapsed_ms=7852 ms)
pref_size=10000000
pref_millis=7852
prefill_elapsed_ms=7852
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=1003
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6991406.100155203
REALTIME_END_PERF_FORMAT=6991416.100228940
###############################################################################
################################## 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 255 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.159s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 254 499 959 1819 3394 6130 10735 18144 29342 45746 68084 97336 133980 177801 227865 280968 335173 386646 429769 462559 480412 480370 465527 436497 395166 346564 294373 242720 193371 149935 113161 82944 59339 41170 28084 19126 12640 8277 5272 3351 2026 1117 637 334 163 76 33 17 9 6 3 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 2 16 64 132 353 821 1752 3674 6796 12429 20475 31940 47468 66838 91572 118381 148151 179147 206745 231376 250521 257060 256429 247863 230369 206882 179186 151217 122537 96310 73636 54929 39738 27888 19071 13168 8713 5810 3717 2378 1501 816 477 255 128 66 21 10 5 6 3 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 501 975 1883 3526 6483 11556 19896 33016 52542 80513 117811 165920 225269 294703 372540 453554 534797 608916 669304 711788 730891 722587 692926 643029 576933 501255 421906 344588 272472 209471 156580 114268 80908 55972 38197 25808 16990 11082 7068 4404 2618 1453 811 418 204 99 38 19 11 9 5 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 501 975 1883 3526 6483 11556 19896 33016 52542 80513 117811 165920 225269 294703 372540 453554 534797 608916 669304 711788 730891 722587 692926 643029 576933 501255 421906 344588 272472 209471 156580 114268 80908 55972 38197 25808 16990 11082 7068 4404 2618 1453 811 418 204 99 38 19 11 9 5 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.96863 1.9501 1.94769 1.90653 1.87606 1.83696 1.79275 1.74749 1.70269 1.6617 1.61763 1.58215 1.5502 1.51894 1.49092 1.46327 1.44013 1.41562 1.39338 1.37237 1.3519 1.3314 1.3147 1.29806 1.28267 1.26813 1.25443 1.24145 1.22955 1.2185 1.20728 1.20005 1.18876 1.18295 1.18068 1.17494 1.16855 1.1651 1.16206 1.14898 1.13442 1.12834 1.11975 1.10358 1.09809 1.11275 1.05051 1.05263 1.10526 1.27273 1.22222 1 1
tree_stats_height=61
tree_stats_numInternals=6580180
tree_stats_numLeaves=3418845
tree_stats_numNodes=9999025
tree_stats_numKeys=9999025
tree_stats_avgDegreeInternal=1.51957
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34192
tree_stats_avgKeyDepth=29.4404
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=11340
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:59 2:1554 4:55259 8:370062 16:52917
[2^00, 2^01]: 59
(2^01, 2^02]: 1554
(2^02, 2^03]: 55259
(2^03, 2^04]: 370062
(2^04, 2^05]: 52917
sum_limbo_reclamation_event_size_total=5449116
count_limbo_reclamation_event_size_by_thread=9998 9997 9997 9997 9997 9997 9997 9996 9996 9997 9997 9997 9997 9997 9997 9996 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9996 9997 9997 9997 9997 9997 9997 9997 9997 9996 9997 9997 9997 9996 9997 9997 9997
count_limbo_reclamation_event_size_total=479851
sum_limbo_reclamation_event_count_total=496223
first_thread_announced_epoch_by_thread=22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680 22680
sum_num_inserts_by_thread=237199 231124 228152 236860 238892 230204 236557 232831 234082 237339 234934 228501 236141 235518 235576 239474 236551 235730 237242 234275 237560 238645 237785 231320 234213 234560 233222 237289 237844 230764 239708 233538 238980 237195 235273 221829 236126 235478 237174 236711 235385 235786 234500 233473 238732 238593 234486 221163
sum_num_inserts_total=11274514
sum_num_deletes_by_thread=237450 230217 227530 235258 238476 230512 236488 232329 234146 237102 234949 227899 235217 236316 236009 239228 237635 235694 237082 235153 237567 237818 238244 230917 234052 235193 232854 237487 236824 230787 239080 233762 237971 237345 234185 221310 235991 235347 237789 238385 235836 236004 233145 232964 237663 238601 234762 221478
sum_num_deletes_total=11268051
sum_num_searches_by_thread=4268444 4141537 4104461 4240374 4302101 4159575 4262243 4182563 4221861 4254253 4238956 4102502 4246660 4243934 4247882 4301346 4272634 4245946 4276031 4234029 4277968 4290359 4274297 4168513 4218730 4223013 4199291 4268309 4278470 4152956 4299628 4202952 4300325 4266421 4234620 3989153 4251624 4242172 4278031 4284993 4234982 4245554 4208757 4189520 4287197 4285866 4208232 3990549
sum_num_searches_total=202899814
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4743093 4602878 4560143 4712492 4779469 4620291 4735288 4647723 4690089 4728694 4708839 4558902 4718018 4715768 4719467 4780048 4746820 4717370 4750355 4703457 4753095 4766822 4750326 4630750 4686995 4692766 4665367 4743085 4753138 4614507 4778416 4670252 4777276 4740961 4704078 4432292 4723741 4712997 4752994 4760089 4706203 4717344 4676402 4655957 4763592 4763060 4677480 4433190
average_num_operations_total=4696716
stdev_num_operations_total=11349
sum_num_operations_total=225442379
min_num_operations_total=4432292
max_num_operations_total=4780048
first_time_thread_terminate_by_thread=10000125 10000075 10000073 10000075 10000075 10000075 10000073 10000075 10000074 10000074 10000075 10000076 10000073 10000073 10000074 10000075 10000074 10000074 10000073 10000074 10000074 10000074 10000073 10000074 10000074 10000074 10000074 10000076 10000074 10000073 10000073 10000074 10000074 10000074 10000075 10000079 10000073 10000074 10000075 10000074 10000074 10000075 10000073 10000073 10000074 10000075 10000073 10000073
min_time_thread_terminate_total=10000073
max_time_thread_terminate_total=10000125
first_time_thread_start_by_thread=8 4 3 4 4 4 4 3 4 4 4 3 4 4 4 4 4 4 4 4 3 3 4 3 3 4 3 4 4 4 4 3 4 4 4 3 4 4 4 4 4 4 4 4 3 3 4 3
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=
threads_final_keysum=99981393562651
threads_final_size=9999025
final_keysum=99981393562651
final_size=9999025
validate_result=success
Validation OK.
Structural validation OK.
total_find=202899814
total_rq=0
total_inserts=11274514
total_deletes=11268051
total_updates=22542565
total_queries=202899814
total_ops=225442379
find_throughput=20289981
rq_throughput=0
update_throughput=2254256
query_throughput=20289981
total_throughput=22544237
total find : 202899814
total rq : 0
total inserts : 11274514
total deletes : 11268051
total updates : 22542565
total queries : 202899814
total ops : 225442379
find throughput : 20289981
rq throughput : 0
update throughput : 2254256
query throughput : 20289981
total throughput : 22544237
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=19.5105
PAPI_L3_TCM=6.06819
PAPI_TOT_CYC=5937.95
PAPI_TOT_INS=1571.72
garbage=1014457788857273
total_execution_walltime=23.502s
REALTIME_MAIN_END_PERF_FORMAT=6991416.431471746
Hybrid noREC system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 239553532
[slow htm]
[fallback]
total fallback commit : 4356954
seconds global lock is held : 0.61586
[time_cmd_output] time_elapsed_sec=23.65, faults_major=0, faults_minor=156037, mem_maxresident_kb=624672, user_cputime=1102.25, sys_cputime=2.98, percent_cpu=4671%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.hybridnorec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1