__step=000090
__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.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=data000090.txt
__path_data=data/data000090.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6994343.980617371
binary=./brown_abtree_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_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=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=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


Hybrid noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.006s
prefilling_size=829862 prefilling_elapsed_millis=1.001s
prefilling_size=908826 prefilling_elapsed_millis=1.101s
prefilling_size=987065 prefilling_elapsed_millis=1.201s
prefilling_size=1064827 prefilling_elapsed_millis=1.301s
prefilling_size=1141681 prefilling_elapsed_millis=1.402s
prefilling_size=1217815 prefilling_elapsed_millis=1.502s
prefilling_size=1293317 prefilling_elapsed_millis=1.602s
prefilling_size=1368409 prefilling_elapsed_millis=1.702s
prefilling_size=1443196 prefilling_elapsed_millis=1.803s
prefilling_size=1516760 prefilling_elapsed_millis=1.903s
prefilling_size=1589528 prefilling_elapsed_millis=2.003s
prefilling_size=1661721 prefilling_elapsed_millis=2.103s
prefilling_size=1733433 prefilling_elapsed_millis=2.203s
prefilling_size=1804581 prefilling_elapsed_millis=2.303s
prefilling_size=1874754 prefilling_elapsed_millis=2.404s
prefilling_size=1944409 prefilling_elapsed_millis=2.504s
prefilling_size=2013372 prefilling_elapsed_millis=2.604s
prefilling_size=2081885 prefilling_elapsed_millis=2.704s
prefilling_size=2149648 prefilling_elapsed_millis=2.805s
prefilling_size=2216408 prefilling_elapsed_millis=2.905s
prefilling_size=2282842 prefilling_elapsed_millis=3.005s
prefilling_size=2348870 prefilling_elapsed_millis=3.105s
prefilling_size=2413927 prefilling_elapsed_millis=3.205s
prefilling_size=2478772 prefilling_elapsed_millis=3.305s
prefilling_size=2543098 prefilling_elapsed_millis=3.406s
prefilling_size=2606970 prefilling_elapsed_millis=3.506s
prefilling_size=2669779 prefilling_elapsed_millis=3.606s
prefilling_size=2731815 prefilling_elapsed_millis=3.706s
prefilling_size=2793943 prefilling_elapsed_millis=3.807s
prefilling_size=2854987 prefilling_elapsed_millis=3.907s
prefilling_size=2916102 prefilling_elapsed_millis=4.007s
prefilling_size=2975906 prefilling_elapsed_millis=4.107s
prefilling_size=3035247 prefilling_elapsed_millis=4.207s
prefilling_size=3094292 prefilling_elapsed_millis=4.308s
prefilling_size=3152832 prefilling_elapsed_millis=4.408s
prefilling_size=3210631 prefilling_elapsed_millis=4.508s
prefilling_size=3267922 prefilling_elapsed_millis=4.608s
prefilling_size=3324870 prefilling_elapsed_millis=4.708s
prefilling_size=3381915 prefilling_elapsed_millis=4.809s
prefilling_size=3438125 prefilling_elapsed_millis=4.909s
prefilling_size=3493250 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 6506670 keys with 48 threads...
tid=0 thread_prefill_amount=79154 percent_done_estimate=58.4 elapsed_s=5 est_remaining_s=8 / 14
tid=0 thread_prefill_amount=83320 percent_done_estimate=61.5 elapsed_s=5 est_remaining_s=8 / 13
tid=0 thread_prefill_amount=87486 percent_done_estimate=64.5 elapsed_s=6 est_remaining_s=7 / 13
tid=0 thread_prefill_amount=91652 percent_done_estimate=67.6 elapsed_s=6 est_remaining_s=6 / 12
tid=0 thread_prefill_amount=95818 percent_done_estimate=70.7 elapsed_s=6 est_remaining_s=6 / 12
tid=0 thread_prefill_amount=99984 percent_done_estimate=73.8 elapsed_s=6 est_remaining_s=5 / 12
tid=0 thread_prefill_amount=104150 percent_done_estimate=76.8 elapsed_s=7 est_remaining_s=5 / 11
tid=0 thread_prefill_amount=108316 percent_done_estimate=79.9 elapsed_s=7 est_remaining_s=4 / 11
tid=0 thread_prefill_amount=112482 percent_done_estimate=83.0 elapsed_s=7 est_remaining_s=3 / 10
tid=0 thread_prefill_amount=116648 percent_done_estimate=86.1 elapsed_s=7 est_remaining_s=3 / 10
tid=0 thread_prefill_amount=120814 percent_done_estimate=89.1 elapsed_s=8 est_remaining_s=2 / 10
tid=0 thread_prefill_amount=124980 percent_done_estimate=92.2 elapsed_s=8 est_remaining_s=1 / 9
tid=0 thread_prefill_amount=129146 percent_done_estimate=95.3 elapsed_s=8 est_remaining_s=1 / 9
tid=0 thread_prefill_amount=133312 percent_done_estimate=98.3 elapsed_s=8 est_remaining_s=0 / 9
tid=0 thread_prefill_amount=137478 percent_done_estimate=101.4 elapsed_s=8 est_remaining_s=-0 / 8
tid=0 thread_prefill_amount=141644 percent_done_estimate=104.5 elapsed_s=9 est_remaining_s=-1 / 8
tid=0 thread_prefill_amount=145810 percent_done_estimate=107.6 elapsed_s=9 est_remaining_s=-1 / 8
tid=0 thread_prefill_amount=149976 percent_done_estimate=110.6 elapsed_s=9 est_remaining_s=-2 / 7
tid=0 thread_prefill_amount=154142 percent_done_estimate=113.7 elapsed_s=9 est_remaining_s=-3 / 7
tid=0 thread_prefill_amount=158308 percent_done_estimate=116.8 elapsed_s=10 est_remaining_s=-3 / 7
tid=0 thread_prefill_amount=162474 percent_done_estimate=119.9 elapsed_s=10 est_remaining_s=-4 / 6
tid=0 thread_prefill_amount=166640 percent_done_estimate=122.9 elapsed_s=10 est_remaining_s=-4 / 6
tid=0 thread_prefill_amount=170806 percent_done_estimate=126.0 elapsed_s=10 est_remaining_s=-5 / 6
tid=0 thread_prefill_amount=174972 percent_done_estimate=129.1 elapsed_s=11 est_remaining_s=-5 / 5
tid=0 thread_prefill_amount=179138 percent_done_estimate=132.2 elapsed_s=11 est_remaining_s=-6 / 5
tid=0 thread_prefill_amount=183304 percent_done_estimate=135.2 elapsed_s=11 est_remaining_s=-6 / 5
tid=0 thread_prefill_amount=187470 percent_done_estimate=138.3 elapsed_s=11 est_remaining_s=-7 / 4
tid=0 thread_prefill_amount=191636 percent_done_estimate=141.4 elapsed_s=11 est_remaining_s=-8 / 4
tid=0 thread_prefill_amount=195802 percent_done_estimate=144.4 elapsed_s=12 est_remaining_s=-8 / 4
tid=0 thread_prefill_amount=199968 percent_done_estimate=147.5 elapsed_s=12 est_remaining_s=-9 / 3
tid=0 thread_prefill_amount=204134 percent_done_estimate=150.6 elapsed_s=12 est_remaining_s=-9 / 3
tid=0 thread_prefill_amount=208300 percent_done_estimate=153.7 elapsed_s=12 est_remaining_s=-10 / 3
timing_elapsed 7.45618s
prefilling_size=10000000 prefilling_elapsed_millis=7.474s
finished prefilling to size 10000000 for expected size 10000000 keysum=100000742644809, performing 18701640 updates; total_prefilling_elapsed_ms=7474 ms)
pref_size=10000000
pref_millis=7474
prefill_elapsed_ms=7474
main thread: starting timer...

###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################

REALTIME_START_PERF_FORMAT=6994356.732156011
REALTIME_END_PERF_FORMAT=6994366.732236819

###############################################################################
################################## 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 455 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.015s

tree_stats_numInternalsAtDepth=1 1 8 63 455 3382 25628 199257 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1573204
tree_stats_numNodesAtDepth=1 1 8 63 455 3382 25628 199257 1573204
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9970178
tree_stats_avgDegreeAtDepth=1 8 7.875 7.22222 7.43297 7.57776 7.77497 7.89535 6.3375

tree_stats_height=9
tree_stats_numInternals=228795
tree_stats_numLeaves=1573204
tree_stats_numNodes=1801999
tree_stats_numKeys=9970178

tree_stats_avgDegreeInternal=7.87604
tree_stats_avgDegreeLeaves=6.3375
tree_stats_avgDegree=6.53284
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=687935
first_thread_announced_epoch_by_thread=30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680 30680
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=
sum_fasthtm_abort_total=
sum_htmpostfix_commit_total=
sum_htmpostfix_abort_total=
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=
sum_stmprefix_commit_total=
sum_slow_commit_total=
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=299233 298423 300183 299146 298656 294988 300078 300514 302569 302271 298351 298522 299179 298809 301634 300087 298991 297854 300829 300285 301165 301512 296470 296226 302764 303955 304700 304315 302468 301942 303413 302648 303858 305460 303180 302312 303337 303197 305389 304461 303260 302170 302152 302591 304962 306758 301372 297849
sum_num_inserts_total=14464488
sum_num_deletes_by_thread=297910 298665 300181 299354 299031 295859 299221 299763 302779 302768 298844 297601 299641 299523 300882 299772 298013 298599 298944 301143 301798 301132 296040 295705 302993 304553 304391 303590 301782 302077 302703 302231 304085 305064 301844 301703 303691 303982 304916 304364 304570 303100 302326 303792 305280 305480 302212 299040
sum_num_deletes_total=14462937
sum_num_searches_by_thread=5367428 5370284 5397728 5387399 5374462 5324184 5378799 5409254 5441718 5448885 5366463 5367987 5387867 5384896 5424934 5405880 5370995 5367386 5386350 5409564 5417767 5421135 5338103 5325099 5445718 5477190 5466854 5458051 5440543 5420584 5444777 5459966 5470523 5490332 5455876 5435070 5456857 5459896 5485864 5483626 5479566 5444930 5443462 5468754 5495443 5512256 5428610 5374365
sum_num_searches_total=260273680
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5964571 5967372 5998092 5985899 5972149 5915031 5978098 6009531 6047066 6053924 5963658 5964110 5986687 5983228 6027450 6005739 5967999 5963839 5986123 6010992 6020730 6023779 5930613 5917030 6051475 6085698 6075945 6065956 6044793 6024603 6050893 6064845 6078466 6100856 6060900 6039085 6063885 6067075 6096169 6092451 6087396 6050200 6047940 6075137 6105685 6124494 6032194 5971254
average_num_operations_total=6025023
stdev_num_operations_total=7654
sum_num_operations_total=289201105
min_num_operations_total=5915031
max_num_operations_total=6124494
first_time_thread_terminate_by_thread=10000140 10000082 10000081 10000082 10000082 10000081 10000082 10000082 10000081 10000081 10000082 10000081 10000082 10000082 10000081 10000081 10000081 10000082 10000081 10000081 10000081 10000081 10000081 10000081 10000080 10000082 10000082 10000081 10000082 10000081 10000082 10000082 10000081 10000081 10000082 10000081 10000082 10000081 10000081 10000082 10000082 10000082 10000081 10000081 10000081 10000081 10000081 10000081
min_time_thread_terminate_total=10000080
max_time_thread_terminate_total=10000140
first_time_thread_start_by_thread=9 5 5 5 5 5 5 5 4 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 4 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99724744480290
threads_final_size=9970178
final_keysum=99724744480290
final_size=9970178
validate_result=success
Validation OK.
Structural validation OK.

total_find=260273680
total_rq=0
total_inserts=14464488
total_deletes=14462937
total_updates=28927425
total_queries=260273680
total_ops=289201105
find_throughput=26027368
rq_throughput=0
update_throughput=2892742
query_throughput=26027368
total_throughput=28920110


total find                    : 260273680
total rq                      : 0
total inserts                 : 14464488
total deletes                 : 14462937
total updates                 : 28927425
total queries                 : 260273680
total ops                     : 289201105
find throughput               : 26027368
rq throughput                 : 0
update throughput             : 2892742
query throughput              : 26027368
total throughput              : 28920110

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=18.4081
PAPI_L3_TCM=5.96937
PAPI_TOT_CYC=4617.89
PAPI_TOT_INS=1592.83
garbage=1304431777243310
total_execution_walltime=22.923s
REALTIME_MAIN_END_PERF_FORMAT=6994366.904513705
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 303290154
[slow htm]
[fallback]
total fallback commit         : 4612592
seconds global lock is held   : 1.10557
[time_cmd_output] time_elapsed_sec=23.18, faults_major=0, faults_minor=463839, mem_maxresident_kb=1855404, user_cputime=1086.78, sys_cputime=4.56, percent_cpu=4708%
timeout=false
algorithm=brown_abtree_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=2