__step=000113
__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 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000113.txt
__path_data=data/data000113.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=221190.568644749
binary=./brown_sigouin_int_avl_tm_auto.debra.norec
parsed custom binding: 0-63,128-191,64-127,192-255
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=2000000
PREFILL_THREADS=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63
noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.018s
prefilling_size=462230 prefilling_elapsed_millis=1s
prefilling_size=494634 prefilling_elapsed_millis=1.1s
prefilling_size=525159 prefilling_elapsed_millis=1.201s
prefilling_size=553457 prefilling_elapsed_millis=1.301s
prefilling_size=580601 prefilling_elapsed_millis=1.401s
prefilling_size=605949 prefilling_elapsed_millis=1.501s
prefilling_size=629087 prefilling_elapsed_millis=1.601s
prefilling_size=651766 prefilling_elapsed_millis=1.701s
prefilling_size=672792 prefilling_elapsed_millis=1.801s
prefilling_size=692501 prefilling_elapsed_millis=1.901s
prefilling_size=711056 prefilling_elapsed_millis=2.002s
prefilling_size=728216 prefilling_elapsed_millis=2.102s
prefilling_size=744790 prefilling_elapsed_millis=2.202s
prefilling_size=760770 prefilling_elapsed_millis=2.302s
prefilling_size=775144 prefilling_elapsed_millis=2.402s
prefilling_size=789072 prefilling_elapsed_millis=2.502s
prefilling_size=802148 prefilling_elapsed_millis=2.602s
prefilling_size=814012 prefilling_elapsed_millis=2.702s
prefilling_size=825323 prefilling_elapsed_millis=2.802s
prefilling_size=835732 prefilling_elapsed_millis=2.903s
prefilling_size=845410 prefilling_elapsed_millis=3.003s
prefilling_size=854584 prefilling_elapsed_millis=3.103s
prefilling_size=863220 prefilling_elapsed_millis=3.203s
prefilling_size=871527 prefilling_elapsed_millis=3.304s
prefilling_size=879154 prefilling_elapsed_millis=3.404s
prefilling_size=886108 prefilling_elapsed_millis=3.504s
prefilling_size=893107 prefilling_elapsed_millis=3.604s
prefilling_size=899451 prefilling_elapsed_millis=3.704s
prefilling_size=905683 prefilling_elapsed_millis=3.804s
prefilling_size=911208 prefilling_elapsed_millis=3.904s
prefilling_size=916196 prefilling_elapsed_millis=4.004s
prefilling_size=921414 prefilling_elapsed_millis=4.105s
prefilling_size=926443 prefilling_elapsed_millis=4.205s
prefilling_size=930979 prefilling_elapsed_millis=4.305s
prefilling_size=935014 prefilling_elapsed_millis=4.405s
prefilling_size=938357 prefilling_elapsed_millis=4.505s
prefilling_size=941901 prefilling_elapsed_millis=4.605s
prefilling_size=944877 prefilling_elapsed_millis=4.705s
prefilling_size=947831 prefilling_elapsed_millis=4.805s
prefilling_size=951050 prefilling_elapsed_millis=4.906s
prefilling_size=953492 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 46513 keys with 64 threads...
timing_elapsed 0.0788539s
prefilling_size=1000000 prefilling_elapsed_millis=0.082s
finished prefilling to size 1000000 for expected size 1000000 keysum=999887625285, performing 6621342 updates; total_prefilling_elapsed_ms=82 ms)
pref_size=1000000
pref_millis=82
prefill_elapsed_ms=83
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=221195.818880508
REALTIME_END_PERF_FORMAT=221205.818950356
###############################################################################
################################## 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 (64 threads)...
partitioned into 256 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.018s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32762 64928 117894 158201 121679 41504 4558 66 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 5 545 10380 63245 148844 144388 52830 5731 78
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32767 65473 128274 221446 270523 185892 57388 5797 78
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32767 65473 128274 221446 270523 185892 57388 5797 78
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99994 1.99829 1.96751 1.80727 1.50722 1.23737 1.08545 1.02159 1.00207 1
tree_stats_height=24
tree_stats_numInternals=574359
tree_stats_numLeaves=426046
tree_stats_numNodes=1000405
tree_stats_numKeys=1000405
tree_stats_avgDegreeInternal=1.74178
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42587
tree_stats_avgKeyDepth=18.3166
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=285242
first_thread_announced_epoch_by_thread=9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360 9360
sum_commit_tx_reader_total=146180204
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 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2216846 2221602 2229481 2228331 2243221 2649788 2601392 2111341 2590079 2626032 2219034 2208287 2171316 2197852 2185687 2215867 2230429 2191662 2200493 2209955 2168028 2204774 2206276 2203464 2207154 2225311 2178218 2200238 2186455 2201239 2175398 2622198 2633159 2568224 2581333 2238962 2076916 2192708 2123333 2195296 2224891 2599248 2126274 2110023 2589914 2625511 2177772 2090880 2114181 2089653 2104582 2642480 2646914 2122194 2209945 2101827 2088315 2133643 2150591 2131525 2624137 2091529 2620523 2626273
sum_commit_tx_writer_total=7691553
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 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 116587 117386 117465 117486 117691 140178 136936 110993 135886 138293 116591 115572 114704 116156 114904 116704 116940 115841 115982 116670 114363 116231 116526 116066 115563 116956 114522 115489 114763 115379 114246 138293 138740 135126 135476 117762 109136 116114 111515 115171 116903 136463 111746 110838 136443 137795 115044 109998 111107 109753 110330 139264 138863 111638 116118 110533 110135 113376 113374 111854 137209 109729 138149 138489
sum_abort_tx_total=3290
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 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 55 45 46 45 50 46 48 50 51 47 43 49 63 57 56 49 58 46 48 50 57 36 49 71 61 59 46 58 50 58 38 57 49 49 50 43 51 51 51 60 53 49 54 42 57 52 49 56 51 46 46 41 45 55 56 62 59 47 54 47 52 52 51 68
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 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 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=21.8:11 29.6:244 37.4:3799 45.2:51642 53:6605 60.8:1376 68.6:262 76.4:42 84.2:16 92:2
[ 14.00, 21.80]: 11
( 21.80, 29.60]: 244
( 29.60, 37.40]: 3799
( 37.40, 45.20]: 51642
( 45.20, 53.00]: 6605
( 53.00, 60.80]: 1376
( 60.80, 68.60]: 262
( 68.60, 76.40]: 42
( 76.40, 84.20]: 16
( 84.20, 92.00]: 2
linear_histogram_of_none_tx_wrset_size_full_data=3.7:19406 6.4:4606 9.1:15691 11.8:6101 14.5:12072 17.2:4403 19.9:601 22.6:968 25.3:140 28:11
[ 1.00, 3.70]: 19406
( 3.70, 6.40]: 4606
( 6.40, 9.10]: 15691
( 9.10, 11.80]: 6101
( 11.80, 14.50]: 12072
( 14.50, 17.20]: 4403
( 17.20, 19.90]: 601
( 19.90, 22.60]: 968
( 22.60, 25.30]: 140
( 25.30, 28.00]: 11
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=137702 138126 137327 136164 139238 137818 138429 139272 138887 138171 140444 137265 136517 135775 136348 135679 113920 116033 117501 116176 115991 115974 117122 116120 114996 115753 115983 114713 116016 115017 116101 114097 113948 116569 116435 116159 115913 118416 118094 117004 116967 117373 117289 116370 115257 115714 115490 117125 109563 111904 111155 110155 109425 112006 111148 109788 110196 111190 111374 111398 110576 113243 111983 113005
sum_num_inserts_total=7696907
sum_num_deletes_by_thread=138364 138175 137709 136308 139579 138401 138446 138208 139560 138026 140041 136377 136960 135891 136303 135451 114895 115921 117398 117046 116071 116599 116937 116188 114944 115729 115593 114558 115611 114830 115597 114386 114794 116897 116675 115607 116428 117837 117801 116540 117116 116998 117460 116119 114501 115475 115625 117088 110082 111457 110224 110291 109506 111539 111600 109954 109452 111885 111059 111200 110190 113153 112467 112776
sum_num_deletes_total=7695898
sum_num_searches_by_thread=2482606 2488461 2486310 2453493 2506960 2487087 2487450 2494419 2503297 2484294 2509481 2464686 2462234 2445143 2453706 2432220 2057205 2091905 2112470 2093403 2090655 2088432 2108208 2090494 2071278 2085136 2084151 2063469 2075876 2070744 2084777 2053908 2060902 2102159 2099461 2082242 2087189 2124659 2120829 2099889 2104905 2112575 2111068 2093574 2063058 2077633 2079352 2107581 1979761 2011487 1993533 1978004 1967121 2019834 2002540 1981516 1981230 2014945 1998428 1999736 1991594 2037569 2009382 2021238
sum_num_searches_total=138478952
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2758672 2764762 2761346 2725965 2785777 2763306 2764325 2771899 2781744 2760491 2789966 2738328 2735711 2716809 2726357 2703350 2286020 2323859 2347369 2326625 2322717 2321005 2342267 2322802 2301218 2316618 2315727 2292740 2307503 2300591 2316475 2282391 2289644 2335625 2332571 2314008 2319530 2360912 2356724 2333433 2338988 2346946 2345817 2326063 2292816 2308822 2310467 2341794 2199406 2234848 2214912 2198450 2186052 2243379 2225288 2201258 2200878 2238020 2220861 2222334 2212360 2263965 2233832 2247019
average_num_operations_total=2404246
stdev_num_operations_total=26235
sum_num_operations_total=153871757
min_num_operations_total=2186052
max_num_operations_total=2789966
first_time_thread_terminate_by_thread=10000109 10000070 10000070 10000070 10000072 10000072 10000072 10000070 10000072 10000072 10000072 10000072 10000074 10000074 10000074 10000074 10000071 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000071 10000072 10000071 10000073 10000070 10000070 10000070 10000074 10000074 10000074 10000074 10000073 10000073 10000073 10000073 10000071 10000071 10000070 10000071 10000073 10000073 10000073 10000073 10000074 10000074 10000074 10000074 10000073 10000073 10000073 10000073 10000070 10000070 10000073 10000070 10000072 10000073 10000072 10000072
min_time_thread_terminate_total=10000070
max_time_thread_terminate_total=10000109
first_time_thread_start_by_thread=8 4 4 4 7 7 7 7 5 5 5 4 7 7 7 7 6 6 6 6 8 8 8 8 6 6 6 6 8 8 8 8 6 7 6 6 5 5 5 5 7 7 7 7 5 5 5 5 5 5 5 5 6 6 6 6 5 5 5 5 8 8 8 8
min_time_thread_start_total=4
max_time_thread_start_total=8
sum_duration_all_ops_total=
threads_final_keysum=999959535344
threads_final_size=1000405
final_keysum=999959535344
final_size=1000405
validate_result=success
Validation OK.
Structural validation OK.
total_find=138478952
total_rq=0
total_inserts=7696907
total_deletes=7695898
total_updates=15392805
total_queries=138478952
total_ops=153871757
find_throughput=13847895
rq_throughput=0
update_throughput=1539280
query_throughput=13847895
total_throughput=15387175
total find : 138478952
total rq : 0
total inserts : 7696907
total deletes : 7695898
total updates : 15392805
total queries : 138478952
total ops : 153871757
find throughput : 13847895
rq throughput : 0
update throughput : 1539280
query throughput : 13847895
total throughput : 15387175
elapsed milliseconds : 10000
napping milliseconds overtime : 100
garbage=22955426411909
total_execution_walltime=15.52s
REALTIME_MAIN_END_PERF_FORMAT=221206.89219887
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=15.64, faults_major=0, faults_minor=147858, mem_maxresident_kb=589712, user_cputime=961.98, sys_cputime=16.78, percent_cpu=6256%
timeout=false
algorithm=brown_sigouin_int_avl_tm_auto.debra.norec
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1