__step=000042
__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_sigouin_int_avl_tm_auto.debra.norec -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=data000042.txt
__path_data=data/data000042.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992483.964131198
binary=./brown_sigouin_int_avl_tm_auto.debra.norec
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_sigouin_int_avl_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=48
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
noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.008s
prefilling_size=889815 prefilling_elapsed_millis=1.001s
prefilling_size=974722 prefilling_elapsed_millis=1.101s
prefilling_size=1058649 prefilling_elapsed_millis=1.201s
prefilling_size=1141863 prefilling_elapsed_millis=1.301s
prefilling_size=1224484 prefilling_elapsed_millis=1.402s
prefilling_size=1306010 prefilling_elapsed_millis=1.502s
prefilling_size=1386825 prefilling_elapsed_millis=1.602s
prefilling_size=1466995 prefilling_elapsed_millis=1.702s
prefilling_size=1546273 prefilling_elapsed_millis=1.802s
prefilling_size=1625429 prefilling_elapsed_millis=1.903s
prefilling_size=1703383 prefilling_elapsed_millis=2.003s
prefilling_size=1780655 prefilling_elapsed_millis=2.103s
prefilling_size=1857188 prefilling_elapsed_millis=2.203s
prefilling_size=1933049 prefilling_elapsed_millis=2.303s
prefilling_size=2007986 prefilling_elapsed_millis=2.403s
prefilling_size=2082582 prefilling_elapsed_millis=2.504s
prefilling_size=2156114 prefilling_elapsed_millis=2.604s
prefilling_size=2229128 prefilling_elapsed_millis=2.704s
prefilling_size=2301600 prefilling_elapsed_millis=2.804s
prefilling_size=2373269 prefilling_elapsed_millis=2.904s
prefilling_size=2444347 prefilling_elapsed_millis=3.005s
prefilling_size=2514810 prefilling_elapsed_millis=3.105s
prefilling_size=2584433 prefilling_elapsed_millis=3.205s
prefilling_size=2652967 prefilling_elapsed_millis=3.305s
prefilling_size=2721514 prefilling_elapsed_millis=3.405s
prefilling_size=2789388 prefilling_elapsed_millis=3.506s
prefilling_size=2856555 prefilling_elapsed_millis=3.606s
prefilling_size=2922937 prefilling_elapsed_millis=3.706s
prefilling_size=2988618 prefilling_elapsed_millis=3.806s
prefilling_size=3053230 prefilling_elapsed_millis=3.906s
prefilling_size=3117987 prefilling_elapsed_millis=4.007s
prefilling_size=3181799 prefilling_elapsed_millis=4.107s
prefilling_size=3245012 prefilling_elapsed_millis=4.207s
prefilling_size=3307362 prefilling_elapsed_millis=4.307s
prefilling_size=3369415 prefilling_elapsed_millis=4.407s
prefilling_size=3430164 prefilling_elapsed_millis=4.508s
prefilling_size=3490943 prefilling_elapsed_millis=4.608s
prefilling_size=3551263 prefilling_elapsed_millis=4.708s
prefilling_size=3610445 prefilling_elapsed_millis=4.808s
prefilling_size=3669485 prefilling_elapsed_millis=4.908s
prefilling_size=3727653 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 6272285 keys with 48 threads...
tid=0 thread_prefill_amount=79154 percent_done_estimate=60.6 elapsed_s=5 est_remaining_s=8 / 13
tid=0 thread_prefill_amount=83320 percent_done_estimate=63.8 elapsed_s=6 est_remaining_s=7 / 13
tid=0 thread_prefill_amount=87486 percent_done_estimate=67.0 elapsed_s=6 est_remaining_s=6 / 12
tid=0 thread_prefill_amount=91652 percent_done_estimate=70.1 elapsed_s=6 est_remaining_s=6 / 12
tid=0 thread_prefill_amount=95818 percent_done_estimate=73.3 elapsed_s=6 est_remaining_s=5 / 11
tid=0 thread_prefill_amount=99984 percent_done_estimate=76.5 elapsed_s=6 est_remaining_s=4 / 11
tid=0 thread_prefill_amount=104150 percent_done_estimate=79.7 elapsed_s=7 est_remaining_s=4 / 10
tid=0 thread_prefill_amount=108316 percent_done_estimate=82.9 elapsed_s=7 est_remaining_s=3 / 10
tid=0 thread_prefill_amount=112482 percent_done_estimate=86.1 elapsed_s=7 est_remaining_s=3 / 10
tid=0 thread_prefill_amount=116648 percent_done_estimate=89.3 elapsed_s=7 est_remaining_s=2 / 9
tid=0 thread_prefill_amount=120814 percent_done_estimate=92.5 elapsed_s=7 est_remaining_s=1 / 9
tid=0 thread_prefill_amount=124980 percent_done_estimate=95.6 elapsed_s=8 est_remaining_s=1 / 8
tid=0 thread_prefill_amount=129146 percent_done_estimate=98.8 elapsed_s=8 est_remaining_s=0 / 8
tid=0 thread_prefill_amount=133312 percent_done_estimate=102.0 elapsed_s=8 est_remaining_s=-0 / 8
tid=0 thread_prefill_amount=137478 percent_done_estimate=105.2 elapsed_s=8 est_remaining_s=-1 / 7
tid=0 thread_prefill_amount=141644 percent_done_estimate=108.4 elapsed_s=8 est_remaining_s=-1 / 7
tid=0 thread_prefill_amount=145810 percent_done_estimate=111.6 elapsed_s=9 est_remaining_s=-2 / 7
tid=0 thread_prefill_amount=149976 percent_done_estimate=114.8 elapsed_s=9 est_remaining_s=-3 / 6
tid=0 thread_prefill_amount=154142 percent_done_estimate=118.0 elapsed_s=9 est_remaining_s=-3 / 6
tid=0 thread_prefill_amount=158308 percent_done_estimate=121.1 elapsed_s=9 est_remaining_s=-4 / 6
tid=0 thread_prefill_amount=162474 percent_done_estimate=124.3 elapsed_s=10 est_remaining_s=-4 / 5
tid=0 thread_prefill_amount=166640 percent_done_estimate=127.5 elapsed_s=10 est_remaining_s=-5 / 5
tid=0 thread_prefill_amount=170806 percent_done_estimate=130.7 elapsed_s=10 est_remaining_s=-5 / 5
tid=0 thread_prefill_amount=174972 percent_done_estimate=133.9 elapsed_s=10 est_remaining_s=-6 / 4
tid=0 thread_prefill_amount=179138 percent_done_estimate=137.1 elapsed_s=10 est_remaining_s=-6 / 4
tid=0 thread_prefill_amount=183304 percent_done_estimate=140.3 elapsed_s=11 est_remaining_s=-7 / 4
tid=0 thread_prefill_amount=187470 percent_done_estimate=143.5 elapsed_s=11 est_remaining_s=-7 / 3
tid=0 thread_prefill_amount=191636 percent_done_estimate=146.7 elapsed_s=11 est_remaining_s=-8 / 3
tid=0 thread_prefill_amount=195802 percent_done_estimate=149.8 elapsed_s=11 est_remaining_s=-8 / 3
tid=0 thread_prefill_amount=199968 percent_done_estimate=153.0 elapsed_s=11 est_remaining_s=-9 / 3
tid=0 thread_prefill_amount=204134 percent_done_estimate=156.2 elapsed_s=12 est_remaining_s=-9 / 2
timing_elapsed 6.62112s
prefilling_size=10000000 prefilling_elapsed_millis=6.637s
finished prefilling to size 10000000 for expected size 10000000 keysum=99982719833027, performing 19323836 updates; total_prefilling_elapsed_ms=6637 ms)
pref_size=10000000
pref_millis=6637
prefill_elapsed_ms=6637
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6992495.904164242
REALTIME_END_PERF_FORMAT=6992505.904240166
###############################################################################
################################## 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.049s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262072 520154 968467 1440038 1389801 722158 166896 12595 138 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 70 3624 62412 407453 1157684 1511778 889266 212678 15866 166
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262142 523778 1030879 1847491 2547485 2233936 1056162 225273 16004 166
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262142 523778 1030879 1847491 2547485 2233936 1056162 225273 16004 166
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99998 1.99834 1.97508 1.85269 1.59943 1.33136 1.14951 1.05527 1.01513 1.00175 1
tree_stats_height=28
tree_stats_numInternals=5744462
tree_stats_numLeaves=4260997
tree_stats_numNodes=10005459
tree_stats_numKeys=10005459
tree_stats_avgDegreeInternal=1.74176
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42587
tree_stats_avgKeyDepth=21.6987
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=229823
first_thread_announced_epoch_by_thread=11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438 11438
sum_commit_tx_reader_total=100506670
sum_commit_tx_reader_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2085493 2081929 2087370 2075000 2093956 2100139 2122821 2089769 2092709 2077624 2122897 2112623 2067690 2095999 2110680 2104638 2086768 2088295 2094362 2086003 2078592 2050544 2070085 2083127 2094756 2122630 2118878 2072965 2102366 2126424 2128489 2114450 2112146 2108999 2113423 2084131 2090431 2098161 2100034 2095201 2079738 2091594 2082025 2075811 2074001 2081887 2090668 2088349
sum_commit_tx_writer_total=5290955
sum_commit_tx_writer_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 109938 109554 109834 108849 110489 110302 112421 109816 110024 109678 111392 111513 108636 110746 110954 111450 109657 110236 109892 109898 109383 107899 109582 110075 110756 111516 111403 109430 110588 111442 112007 111357 111155 111094 110971 109805 109705 110058 110522 111063 109281 109629 109156 108827 110031 109402 109909 109630
sum_abort_tx_total=141
sum_abort_tx_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 4 4 7 0 4 2 1 3 1 5 1 3 4 2 5 1 4 4 3 1 3 3 4 1 2 6 4 0 5 4 2 2 3 2 0 2 2 2 4 5 1 4 1 4 4 4 5 3
max_ht_capacity_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048
linear_histogram_of_none_tx_rdset_size_full_data=29.3:20 38.6:544 47.9:11407 57.2:34427 66.5:1277 75.8:263 85.1:48 94.4:6 103.7:6 113:1
[ 20.00, 29.30]: 20
( 29.30, 38.60]: 544
( 38.60, 47.90]: 11407
( 47.90, 57.20]: 34427
( 57.20, 66.50]: 1277
( 66.50, 75.80]: 263
( 75.80, 85.10]: 48
( 85.10, 94.40]: 6
( 94.40, 103.70]: 6
( 103.70, 113.00]: 1
linear_histogram_of_none_tx_wrset_size_full_data=4.2:16580 7.4:4762 10.6:9723 13.8:7354 17:6117 20.2:2415 23.4:957 26.6:84 29.8:6 33:1
[ 1.00, 4.20]: 16580
( 4.20, 7.40]: 4762
( 7.40, 10.60]: 9723
( 10.60, 13.80]: 7354
( 13.80, 17.00]: 6117
( 17.00, 20.20]: 2415
( 20.20, 23.40]: 957
( 23.40, 26.60]: 84
( 26.60, 29.80]: 6
( 29.80, 33.00]: 1
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=109812 110324 110805 109896 109740 107638 110533 110234 111640 111713 108923 110348 109137 109291 111310 110233 109696 110535 109864 109213 109775 109972 110757 110287 109291 109988 111711 110237 111138 109744 109708 110328 111389 111918 111315 110416 111222 109234 111722 109683 110175 110903 109949 109197 109701 109740 109056 110881
sum_num_inserts_total=5290322
sum_num_deletes_by_thread=110010 109410 110573 109925 109511 107975 111131 110646 112651 110844 109209 109887 109055 108751 111971 109940 109556 111584 109041 109710 110514 110312 111281 109796 109768 109878 111794 110297 111933 108721 109501 110507 111019 111752 111493 110787 111731 109339 111757 110089 110097 111342 110386 108177 109975 109637 109522 110685
sum_num_deletes_total=5293470
sum_num_searches_by_thread=1980314 1978245 1984886 1977383 1971930 1942830 1983848 1992074 2016205 2007724 1966506 1984210 1965657 1964353 2011008 1975728 1977173 1999515 1972384 1972560 1982444 1979301 2002356 1973119 1974877 1980711 2014361 1987685 2002736 1969510 1968093 1985910 1997685 2011572 2000493 1989238 2001183 1961094 2010667 1984482 1978259 1993843 1980888 1958952 1975755 1964655 1970441 1988990
sum_num_searches_total=95213833
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2200136 2197979 2206264 2197204 2191181 2158443 2205512 2212954 2240496 2230281 2184638 2204445 2183849 2182395 2234289 2195901 2196425 2221634 2191289 2191483 2202733 2199585 2224394 2193202 2193936 2200577 2237866 2208219 2225807 2187975 2187302 2206745 2220093 2235242 2223301 2210441 2224136 2179667 2234146 2204254 2198531 2216088 2201223 2176326 2195431 2184032 2189019 2210556
average_num_operations_total=2204117
stdev_num_operations_total=2645
sum_num_operations_total=105797625
min_num_operations_total=2158443
max_num_operations_total=2240496
first_time_thread_terminate_by_thread=10000128 10000078 10000080 10000076 10000078 10000078 10000078 10000080 10000076 10000076 10000079 10000080 10000078 10000077 10000080 10000080 10000075 10000076 10000079 10000078 10000080 10000079 10000077 10000078 10000078 10000078 10000080 10000080 10000079 10000080 10000077 10000080 10000078 10000077 10000080 10000077 10000080 10000079 10000080 10000079 10000075 10000080 10000078 10000076 10000078 10000079 10000075 10000078
min_time_thread_terminate_total=10000075
max_time_thread_terminate_total=10000128
first_time_thread_start_by_thread=9 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 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=9
sum_duration_all_ops_total=
threads_final_keysum=100030670178775
threads_final_size=10005459
final_keysum=100030670178775
final_size=10005459
validate_result=success
Validation OK.
Structural validation OK.
total_find=95213833
total_rq=0
total_inserts=5290322
total_deletes=5293470
total_updates=10583792
total_queries=95213833
total_ops=105797625
find_throughput=9521383
rq_throughput=0
update_throughput=1058379
query_throughput=9521383
total_throughput=10579762
total find : 95213833
total rq : 0
total inserts : 5290322
total deletes : 5293470
total updates : 10583792
total queries : 95213833
total ops : 105797625
find throughput : 9521383
rq throughput : 0
update throughput : 1058379
query throughput : 9521383
total throughput : 10579762
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=21.6081
PAPI_L3_TCM=5.94447
PAPI_TOT_CYC=11052.4
PAPI_TOT_INS=6618.96
garbage=475284147401970
total_execution_walltime=22.289s
REALTIME_MAIN_END_PERF_FORMAT=6992506.254612653
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=22.47, faults_major=0, faults_minor=231876, mem_maxresident_kb=928192, user_cputime=1026.45, sys_cputime=24.62, percent_cpu=4675%
timeout=false
algorithm=brown_sigouin_int_avl_tm_auto.debra.norec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2