__step=000081
__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.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=data000081.txt
__path_data=data/data000081.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6994033.588945238
binary=./brown_abtree_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_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
noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.006s
prefilling_size=947918 prefilling_elapsed_millis=1.001s
prefilling_size=1038628 prefilling_elapsed_millis=1.101s
prefilling_size=1128285 prefilling_elapsed_millis=1.201s
prefilling_size=1216901 prefilling_elapsed_millis=1.301s
prefilling_size=1305041 prefilling_elapsed_millis=1.401s
prefilling_size=1392165 prefilling_elapsed_millis=1.502s
prefilling_size=1479111 prefilling_elapsed_millis=1.602s
prefilling_size=1565210 prefilling_elapsed_millis=1.702s
prefilling_size=1649997 prefilling_elapsed_millis=1.802s
prefilling_size=1734001 prefilling_elapsed_millis=1.902s
prefilling_size=1817451 prefilling_elapsed_millis=2.003s
prefilling_size=1899575 prefilling_elapsed_millis=2.103s
prefilling_size=1981486 prefilling_elapsed_millis=2.203s
prefilling_size=2062607 prefilling_elapsed_millis=2.303s
prefilling_size=2142674 prefilling_elapsed_millis=2.403s
prefilling_size=2221936 prefilling_elapsed_millis=2.503s
prefilling_size=2301155 prefilling_elapsed_millis=2.604s
prefilling_size=2379082 prefilling_elapsed_millis=2.704s
prefilling_size=2456341 prefilling_elapsed_millis=2.804s
prefilling_size=2532755 prefilling_elapsed_millis=2.904s
prefilling_size=2608637 prefilling_elapsed_millis=3.004s
prefilling_size=2683804 prefilling_elapsed_millis=3.105s
prefilling_size=2758191 prefilling_elapsed_millis=3.205s
prefilling_size=2831798 prefilling_elapsed_millis=3.305s
prefilling_size=2905004 prefilling_elapsed_millis=3.405s
prefilling_size=2977368 prefilling_elapsed_millis=3.505s
prefilling_size=3049339 prefilling_elapsed_millis=3.605s
prefilling_size=3120120 prefilling_elapsed_millis=3.706s
prefilling_size=3190444 prefilling_elapsed_millis=3.806s
prefilling_size=3260101 prefilling_elapsed_millis=3.906s
prefilling_size=3329362 prefilling_elapsed_millis=4.006s
prefilling_size=3397742 prefilling_elapsed_millis=4.106s
prefilling_size=3465241 prefilling_elapsed_millis=4.207s
prefilling_size=3532212 prefilling_elapsed_millis=4.307s
prefilling_size=3599063 prefilling_elapsed_millis=4.407s
prefilling_size=3664781 prefilling_elapsed_millis=4.507s
prefilling_size=3730001 prefilling_elapsed_millis=4.607s
prefilling_size=3794599 prefilling_elapsed_millis=4.708s
prefilling_size=3858348 prefilling_elapsed_millis=4.808s
prefilling_size=3921972 prefilling_elapsed_millis=4.908s
prefilling_size=3983896 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 6016045 keys with 48 threads...
tid=0 thread_prefill_amount=83320 percent_done_estimate=66.5 elapsed_s=5 est_remaining_s=6 / 11
tid=0 thread_prefill_amount=87486 percent_done_estimate=69.8 elapsed_s=6 est_remaining_s=5 / 11
tid=0 thread_prefill_amount=91652 percent_done_estimate=73.1 elapsed_s=6 est_remaining_s=5 / 10
tid=0 thread_prefill_amount=95818 percent_done_estimate=76.4 elapsed_s=6 est_remaining_s=4 / 10
tid=0 thread_prefill_amount=99984 percent_done_estimate=79.8 elapsed_s=6 est_remaining_s=4 / 10
tid=0 thread_prefill_amount=104150 percent_done_estimate=83.1 elapsed_s=6 est_remaining_s=3 / 9
tid=0 thread_prefill_amount=108316 percent_done_estimate=86.4 elapsed_s=7 est_remaining_s=2 / 9
tid=0 thread_prefill_amount=112482 percent_done_estimate=89.7 elapsed_s=7 est_remaining_s=2 / 9
tid=0 thread_prefill_amount=116648 percent_done_estimate=93.1 elapsed_s=7 est_remaining_s=1 / 8
tid=0 thread_prefill_amount=120814 percent_done_estimate=96.4 elapsed_s=7 est_remaining_s=1 / 8
tid=0 thread_prefill_amount=124980 percent_done_estimate=99.7 elapsed_s=7 est_remaining_s=0 / 7
tid=0 thread_prefill_amount=129146 percent_done_estimate=103.0 elapsed_s=8 est_remaining_s=-1 / 7
tid=0 thread_prefill_amount=133312 percent_done_estimate=106.4 elapsed_s=8 est_remaining_s=-1 / 7
tid=0 thread_prefill_amount=137478 percent_done_estimate=109.7 elapsed_s=8 est_remaining_s=-2 / 6
tid=0 thread_prefill_amount=141644 percent_done_estimate=113.0 elapsed_s=8 est_remaining_s=-2 / 6
tid=0 thread_prefill_amount=145810 percent_done_estimate=116.3 elapsed_s=8 est_remaining_s=-3 / 6
tid=0 thread_prefill_amount=149976 percent_done_estimate=119.7 elapsed_s=9 est_remaining_s=-3 / 5
tid=0 thread_prefill_amount=154142 percent_done_estimate=123.0 elapsed_s=9 est_remaining_s=-4 / 5
tid=0 thread_prefill_amount=158308 percent_done_estimate=126.3 elapsed_s=9 est_remaining_s=-4 / 5
tid=0 thread_prefill_amount=162474 percent_done_estimate=129.6 elapsed_s=9 est_remaining_s=-5 / 5
tid=0 thread_prefill_amount=166640 percent_done_estimate=133.0 elapsed_s=10 est_remaining_s=-5 / 4
tid=0 thread_prefill_amount=170806 percent_done_estimate=136.3 elapsed_s=10 est_remaining_s=-6 / 4
tid=0 thread_prefill_amount=174972 percent_done_estimate=139.6 elapsed_s=10 est_remaining_s=-6 / 4
tid=0 thread_prefill_amount=179138 percent_done_estimate=142.9 elapsed_s=10 est_remaining_s=-7 / 3
tid=0 thread_prefill_amount=183304 percent_done_estimate=146.3 elapsed_s=10 est_remaining_s=-7 / 3
tid=0 thread_prefill_amount=187470 percent_done_estimate=149.6 elapsed_s=11 est_remaining_s=-8 / 3
tid=0 thread_prefill_amount=191636 percent_done_estimate=152.9 elapsed_s=11 est_remaining_s=-8 / 2
tid=0 thread_prefill_amount=195802 percent_done_estimate=156.2 elapsed_s=11 est_remaining_s=-9 / 2
tid=0 thread_prefill_amount=199968 percent_done_estimate=159.5 elapsed_s=11 est_remaining_s=-9 / 2
tid=0 thread_prefill_amount=204134 percent_done_estimate=162.9 elapsed_s=11 est_remaining_s=-10 / 1
timing_elapsed 5.99649s
prefilling_size=10000000 prefilling_elapsed_millis=6.01s
finished prefilling to size 10000000 for expected size 10000000 keysum=99994425679977, performing 19585618 updates; total_prefilling_elapsed_ms=6010 ms)
pref_size=10000000
pref_millis=6010
prefill_elapsed_ms=6011
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6994044.888160979
REALTIME_END_PERF_FORMAT=6994054.888237575
###############################################################################
################################## 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 432 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.014s
tree_stats_numInternalsAtDepth=1 1 8 62 432 3110 23863 185591 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1467747
tree_stats_numNodesAtDepth=1 1 8 62 432 3110 23863 185591 1467747
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10002740
tree_stats_avgDegreeAtDepth=1 8 7.75 6.96774 7.19907 7.67299 7.77735 7.9085 6.81503
tree_stats_height=9
tree_stats_numInternals=213068
tree_stats_numLeaves=1467747
tree_stats_numNodes=1680815
tree_stats_numKeys=10002740
tree_stats_avgDegreeInternal=7.88863
tree_stats_avgDegreeLeaves=6.81503
tree_stats_avgDegree=6.95112
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=281470
first_thread_announced_epoch_by_thread=13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674 13674
sum_commit_tx_reader_total=123742614
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 2574455 2567548 2581459 2589723 2572651 2588555 2607391 2590731 2575456 2570576 2567357 2554134 2560882 2566973 2576181 2556324 2568908 2598009 2601348 2594578 2578382 2571150 2579766 2582020 2565614 2575847 2591955 2600729 2575484 2574010 2582622 2581946 2580690 2589334 2570213 2582488 2584660 2597100 2589701 2580665 2590906 2568323 2570032 2565675 2581359 2574989 2559525 2534190
sum_commit_tx_writer_total=6518652
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 136155 135441 136069 136279 135730 136006 136683 136631 134870 135998 134905 135490 134662 135498 136021 134586 135388 136968 136620 136543 135751 135371 136110 135671 135647 134962 136557 136550 135728 135430 136403 136009 136058 136726 135762 135352 135996 137134 136524 136362 136439 134717 135112 136013 135825 135638 134729 133533
sum_abort_tx_total=241
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 7 6 3 7 6 8 8 4 1 5 4 4 4 5 5 5 4 5 6 6 2 6 8 6 3 5 6 4 5 6 5 5 7 2 5 7 3 2 5 5 7 4 10 3 3 2 7 5
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=35.2:194 43.4:3872 51.6:16024 59.8:19876 68:7195 76.2:746 84.4:65 92.6:14 100.8:10 109:3
[ 27.00, 35.20]: 194
( 35.20, 43.40]: 3872
( 43.40, 51.60]: 16024
( 51.60, 59.80]: 19876
( 59.80, 68.00]: 7195
( 68.00, 76.20]: 746
( 76.20, 84.40]: 65
( 84.40, 92.60]: 14
( 92.60, 100.80]: 10
( 100.80, 109.00]: 3
linear_histogram_of_none_tx_wrset_size_full_data=29.7:44977 85.1:2621 140.5:353 195.9:46 251.3:2
[ 2.00, 29.70]: 44977
( 57.40, 85.10]: 2621
( 112.80, 140.50]: 353
( 168.20, 195.90]: 46
( 223.60, 251.30]: 2
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=133420 134990 135925 135406 136816 136427 134977 136132 136992 136978 135622 136627 134779 136060 136663 135805 135211 136316 136180 134852 135284 136831 135812 135155 135481 136348 136468 136083 136197 134981 134880 136639 136727 136515 135830 135534 135032 135447 136048 135843 135357 136826 134737 135453 135725 136505 135492 135150
sum_num_inserts_total=6518558
sum_num_deletes_by_thread=133584 134449 135530 134723 135913 136573 135323 135009 136592 136500 135583 136569 134602 135944 136801 135964 136071 136105 135643 134635 135482 136706 136006 133881 134812 135441 136465 135439 135780 135940 135679 136497 136586 137155 136030 135971 134424 135186 135688 135723 135372 136195 135078 135788 135792 136897 135633 135201
sum_num_deletes_total=6512960
sum_num_searches_by_thread=2400719 2432823 2435119 2432342 2453331 2454345 2440509 2434834 2461393 2453884 2446486 2445829 2426163 2452557 2457657 2440433 2439930 2444606 2429865 2421423 2435755 2463742 2445366 2425218 2432696 2445739 2453069 2436859 2444771 2439706 2430702 2453089 2464655 2470404 2448796 2446450 2420168 2439693 2442397 2439044 2438711 2461213 2433225 2433055 2444359 2455110 2446715 2434793
sum_num_searches_total=117229748
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2667723 2702262 2706574 2702471 2726060 2727345 2710809 2705975 2734977 2727362 2717691 2719025 2695544 2724561 2731121 2712202 2711212 2717027 2701688 2690910 2706521 2737279 2717184 2694254 2702989 2717528 2726002 2708381 2716748 2710627 2701261 2726225 2737968 2744074 2720656 2717955 2689624 2710326 2714133 2710610 2709440 2734234 2703040 2704296 2715876 2728512 2717840 2705144
average_num_operations_total=2713776
stdev_num_operations_total=2097
sum_num_operations_total=130261266
min_num_operations_total=2667723
max_num_operations_total=2744074
first_time_thread_terminate_by_thread=10000128 10000076 10000076 10000077 10000077 10000076 10000077 10000077 10000076 10000076 10000078 10000078 10000078 10000077 10000077 10000076 10000076 10000077 10000076 10000076 10000077 10000076 10000077 10000076 10000076 10000076 10000076 10000076 10000077 10000077 10000077 10000077 10000077 10000077 10000078 10000077 10000077 10000077 10000076 10000076 10000076 10000077 10000076 10000076 10000076 10000076 10000077 10000076
min_time_thread_terminate_total=10000076
max_time_thread_terminate_total=10000128
first_time_thread_start_by_thread=10 4 4 5 5 5 5 4 5 5 5 5 5 5 5 4 5 5 4 5 5 5 5 4 4 4 4 5 5 5 5 4 5 5 5 5 5 5 5 4 5 5 4 5 5 5 5 4
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=
threads_final_keysum=100041739029600
threads_final_size=10002740
final_keysum=100041739029600
final_size=10002740
validate_result=success
Validation OK.
Structural validation OK.
total_find=117229748
total_rq=0
total_inserts=6518558
total_deletes=6512960
total_updates=13031518
total_queries=117229748
total_ops=130261266
find_throughput=11722974
rq_throughput=0
update_throughput=1303151
query_throughput=11722974
total_throughput=13026126
total find : 117229748
total rq : 0
total inserts : 6518558
total deletes : 6512960
total updates : 13031518
total queries : 117229748
total ops : 130261266
find throughput : 11722974
rq throughput : 0
update throughput : 1303151
query throughput : 11722974
total throughput : 13026126
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=20.3841
PAPI_L3_TCM=5.98212
PAPI_TOT_CYC=8674.74
PAPI_TOT_INS=6279.4
garbage=574086226750642
total_execution_walltime=21.623s
REALTIME_MAIN_END_PERF_FORMAT=6994055.213207603
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=21.86, faults_major=0, faults_minor=435890, mem_maxresident_kb=1743656, user_cputime=987.76, sys_cputime=31.59, percent_cpu=4661%
timeout=false
algorithm=brown_abtree_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=1