__step=000259
__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 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000259.txt
__path_data=data/data000259.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997677.118817377
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=2000000
PREFILL_THREADS=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143


noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.015s
prefilling_size=334796 prefilling_elapsed_millis=1.001s
prefilling_size=361537 prefilling_elapsed_millis=1.101s
prefilling_size=387443 prefilling_elapsed_millis=1.201s
prefilling_size=412165 prefilling_elapsed_millis=1.301s
prefilling_size=435610 prefilling_elapsed_millis=1.402s
prefilling_size=458103 prefilling_elapsed_millis=1.502s
prefilling_size=480060 prefilling_elapsed_millis=1.602s
prefilling_size=501035 prefilling_elapsed_millis=1.702s
prefilling_size=520861 prefilling_elapsed_millis=1.802s
prefilling_size=539736 prefilling_elapsed_millis=1.903s
prefilling_size=557782 prefilling_elapsed_millis=2.003s
prefilling_size=575300 prefilling_elapsed_millis=2.103s
prefilling_size=592416 prefilling_elapsed_millis=2.203s
prefilling_size=608991 prefilling_elapsed_millis=2.303s
prefilling_size=624832 prefilling_elapsed_millis=2.404s
prefilling_size=639502 prefilling_elapsed_millis=2.504s
prefilling_size=654119 prefilling_elapsed_millis=2.604s
prefilling_size=667975 prefilling_elapsed_millis=2.704s
prefilling_size=681673 prefilling_elapsed_millis=2.804s
prefilling_size=694276 prefilling_elapsed_millis=2.905s
prefilling_size=706558 prefilling_elapsed_millis=3.005s
prefilling_size=718088 prefilling_elapsed_millis=3.105s
prefilling_size=729350 prefilling_elapsed_millis=3.205s
prefilling_size=740150 prefilling_elapsed_millis=3.305s
prefilling_size=750319 prefilling_elapsed_millis=3.406s
prefilling_size=759876 prefilling_elapsed_millis=3.506s
prefilling_size=769487 prefilling_elapsed_millis=3.606s
prefilling_size=778446 prefilling_elapsed_millis=3.706s
prefilling_size=787434 prefilling_elapsed_millis=3.806s
prefilling_size=795975 prefilling_elapsed_millis=3.907s
prefilling_size=804240 prefilling_elapsed_millis=4.007s
prefilling_size=811661 prefilling_elapsed_millis=4.107s
prefilling_size=818791 prefilling_elapsed_millis=4.207s
prefilling_size=825794 prefilling_elapsed_millis=4.307s
prefilling_size=832820 prefilling_elapsed_millis=4.408s
prefilling_size=839156 prefilling_elapsed_millis=4.508s
prefilling_size=845527 prefilling_elapsed_millis=4.608s
prefilling_size=851459 prefilling_elapsed_millis=4.708s
prefilling_size=857787 prefilling_elapsed_millis=4.808s
prefilling_size=863233 prefilling_elapsed_millis=4.908s
prefilling_size=868377 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 131604 keys with 96 threads...
timing_elapsed 0.32073s
prefilling_size=1000000 prefilling_elapsed_millis=0.321s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000104984397, performing 4375830 updates; total_prefilling_elapsed_ms=321 ms)
pref_size=1000000
pref_millis=321
prefill_elapsed_ms=321
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6997682.740162276
REALTIME_END_PERF_FORMAT=6997692.740247449

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

tree_stats_computeWalltime=0.011s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32719 64281 115526 155450 121512 44356 5943 214 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 45 1039 11118 61478 144844 142661 56105 7457 259
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32764 65320 126644 216928 266356 187017 62048 7671 259
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32764 65320 126644 216928 266356 187017 62048 7671 259
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99976 1.99503 1.95473 1.80069 1.51126 1.24593 1.0946 1.02785 1.00587 1

tree_stats_height=24
tree_stats_numInternals=572768
tree_stats_numLeaves=425006
tree_stats_numNodes=997774
tree_stats_numKeys=997774

tree_stats_avgDegreeInternal=1.74202
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42595
tree_stats_avgKeyDepth=18.3399


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=123652
first_thread_announced_epoch_by_thread=2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684 2684
sum_commit_tx_reader_total=107213840
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 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1135769 1138610 1126597 1131863 1112733 1127021 1127932 1090992 1136993 1138677 1090466 1122754 1083530 1139354 1124325 1139478 1098403 1139638 1142377 1131998 1131557 1101624 1096818 1122803 1099778 1130028 1093616 1102566 1114510 1094871 1101935 1098495 1130653 1106067 1136453 1120985 1098158 1150772 1151556 1132936 1101303 1116146 1087539 1131607 1128669 1149953 1099315 1134461 1131416 1131395 1132279 1127938 1104810 1109933 1142599 1159796 1095756 1125829 1105726 1140178 1118878 1128850 1120383 1136569 1129931 1121706 1102218 1082095 1108423 1098073 1107010 1118337 1098461 1110166 1118606 1121906 1122184 1103767 1104177 1130702 1117618 1124867 1115887 1091033 1090919 1103861 1094130 1109483 1097448 1093607 1095103 1098840 1088867 1094725 1134619 1128052
sum_commit_tx_writer_total=5643188
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 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 59874 59891 59370 59577 58770 59198 59053 57469 59865 59875 57251 59465 57063 60304 59347 60058 57408 59954 59980 59621 59279 57400 57790 59096 58056 59330 57624 58079 58681 57601 57761 57630 59071 58489 59520 59245 57655 60657 60600 59990 58015 59005 57625 59438 59382 60359 57765 59747 59328 59556 59368 59330 58197 58706 59914 61568 57688 58978 57999 60051 58838 59520 58990 59692 59880 59047 57883 57038 58221 57748 58740 59130 57355 58860 59285 59111 59185 58158 58149 59424 58801 59264 59285 57467 57693 58384 57616 58241 58062 57843 57288 57668 57089 56735 59395 59137
sum_abort_tx_total=3098
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 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 40 36 24 37 27 34 35 38 30 32 26 36 33 30 26 28 38 37 31 36 30 29 27 38 36 33 32 36 34 24 33 31 37 35 32 35 31 34 28 33 38 38 30 27 35 38 25 32 26 34 32 37 26 28 33 36 37 25 26 40 29 37 33 28 25 31 36 32 46 32 36 37 28 37 38 44 39 38 22 37 26 40 28 30 26 30 29 27 28 30 34 29 27 30 23 31
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 0 0 0 0 0 0 0 0 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 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=20.9:12 29.8:361 38.7:13257 47.6:77061 56.5:4107 65.4:976 74.3:166 83.2:41 92.1:16 101:2
    [       12.00,        20.90]: 12
    (       20.90,        29.80]: 361
    (       29.80,        38.70]: 13257
    (       38.70,        47.60]: 77061
    (       47.60,        56.50]: 4107
    (       56.50,        65.40]: 976
    (       65.40,        74.30]: 166
    (       74.30,        83.20]: 41
    (       83.20,        92.10]: 16
    (       92.10,       101.00]: 2

linear_histogram_of_none_tx_wrset_size_full_data=3.9:29416 6.8:6757 9.7:22953 12.6:11248 15.5:16927 18.4:6508 21.3:1414 24.2:695 27.1:71 30:9
    [        1.00,         3.90]: 29416
    (        3.90,         6.80]: 6757
    (        6.80,         9.70]: 22953
    (        9.70,        12.60]: 11248
    (       12.60,        15.50]: 16927
    (       15.50,        18.40]: 6508
    (       18.40,        21.30]: 1414
    (       21.30,        24.20]: 695
    (       24.20,        27.10]: 71
    (       27.10,        30.00]: 9
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=59139 59263 59132 59050 59627 58608 60371 60031 59889 60216 59993 59092 61572 59702 59882 59499 59024 59557 60113 59129 59203 59661 59775 59310 59978 59048 59308 59369 59791 58815 60293 59305 59445 60319 59912 59392 60671 59402 59455 59142 60048 59442 59681 58962 58990 59381 60301 59041 58721 57685 57506 58026 58069 57684 59257 57556 57925 57938 59309 57839 59043 56887 57770 57213 57682 57299 58079 56653 58654 58183 57609 57603 58609 56692 57556 58707 57823 57940 59183 58211 58356 58324 58730 57148 59296 57684 58676 57078 57316 57521 57326 56843 58118 58216 56883 57627
sum_num_inserts_total=5640382
sum_num_deletes_by_thread=59853 58675 59182 59009 59229 58965 60893 59492 59711 60093 60010 59215 61216 59670 60179 59265 59193 59526 59842 59118 59026 59522 59825 59935 59960 58515 59049 59277 59352 58728 60776 59531 59714 59981 60152 59659 60543 59678 59498 58810 59635 59558 59630 59261 59531 59552 59981 59056 58701 57865 57358 58633 57805 57843 58753 57915 57981 58388 58824 57543 59198 57813 58230 57452 57740 57201 57910 57096 58140 57745 57358 57705 58739 57787 57342 58382 57630 57877 58859 57690 57947 58449 58687 57786 59283 57934 58457 57099 57279 57748 57814 57061 58106 58262 57078 58241
sum_num_deletes_total=5642805
sum_num_searches_by_thread=1075022 1063961 1062439 1057113 1072791 1060143 1090165 1076120 1076661 1079227 1082510 1072529 1098576 1070439 1079531 1070594 1068972 1072357 1078597 1061126 1066578 1072436 1078901 1069125 1080291 1057588 1067610 1065026 1068125 1058876 1091087 1071290 1071886 1079358 1072862 1071900 1089098 1071664 1069098 1066179 1074525 1076973 1077547 1063146 1063698 1070791 1082075 1059794 1055769 1040263 1037527 1051065 1041960 1043497 1068975 1037001 1046019 1043775 1059334 1036068 1062776 1036540 1044645 1033796 1040394 1033217 1043329 1026844 1051845 1047797 1040844 1038136 1054155 1030685 1036848 1048661 1039155 1043879 1062188 1039609 1046023 1049871 1051609 1033678 1067640 1040507 1047423 1031779 1033905 1040552 1041368 1025229 1046783 1045767 1037499 1041212
sum_num_searches_total=101573841
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1194014 1181899 1180753 1175172 1191647 1177716 1211429 1195643 1196261 1199536 1202513 1190836 1221364 1189811 1199592 1189358 1187189 1191440 1198552 1179373 1184807 1191619 1198501 1188370 1200229 1175151 1185967 1183672 1187268 1176419 1212156 1190126 1191045 1199658 1192926 1190951 1210312 1190744 1188051 1184131 1194208 1195973 1196858 1181369 1182219 1189724 1202357 1177891 1173191 1155813 1152391 1167724 1157834 1159024 1186985 1152472 1161925 1160101 1177467 1151450 1181017 1151240 1160645 1148461 1155816 1147717 1159318 1140593 1168639 1163725 1155811 1153444 1171503 1145164 1151746 1165750 1154608 1159696 1180230 1155510 1162326 1166644 1169026 1148612 1186219 1156125 1164556 1145956 1148500 1155821 1156508 1139133 1163007 1162245 1151460 1157080
average_num_operations_total=1175594
stdev_num_operations_total=1967
sum_num_operations_total=112857028
min_num_operations_total=1139133
max_num_operations_total=1221364
first_time_thread_terminate_by_thread=10000150 10000088 10000092 10000093 10000102 10000093 10000093 10000099 10000085 10000089 10000085 10000085 10000091 10000093 10000099 10000084 10000093 10000092 10000089 10000093 10000088 10000084 10000091 10000093 10000093 10000093 10000089 10000093 10000093 10000089 10000093 10000099 10000099 10000093 10000090 10000094 10000085 10000089 10000099 10000088 10000093 10000090 10000089 10000093 10000088 10000084 10000093 10000092 10000086 10000090 10000085 10000088 10000088 10000089 10000086 10000087 10000088 10000085 10000096 10000085 10000085 10000085 10000092 10000098 10000085 10000096 10000090 10000085 10000085 10000085 10000085 10000085 10000092 10000085 10000085 10000085 10000089 10000085 10000088 10000087 10000092 10000090 10000096 10000085 10000090 10000088 10000088 10000096 10000085 10000096 10000088 10000089 10000090 10000098 10000088 10000103
min_time_thread_terminate_total=10000084
max_time_thread_terminate_total=10000150
first_time_thread_start_by_thread=9 5 5 5 6 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 6 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 6 5 6 6 6 5 6 6 6 5 6 5 5 5 6 6 6 5 6 5 6 6 6 6 6 5 6 6 6 5 6 6 6 5 6 5 5 5 6 6 6 5 6 5 6 6 6 6
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=997911182422
threads_final_size=997774
final_keysum=997911182422
final_size=997774
validate_result=success
Validation OK.
Structural validation OK.

total_find=101573841
total_rq=0
total_inserts=5640382
total_deletes=5642805
total_updates=11283187
total_queries=101573841
total_ops=112857028
find_throughput=10157384
rq_throughput=0
update_throughput=1128318
query_throughput=10157384
total_throughput=11285702


total find                    : 101573841
total rq                      : 0
total inserts                 : 5640382
total deletes                 : 5642805
total updates                 : 11283187
total queries                 : 101573841
total ops                     : 112857028
find throughput               : 10157384
rq throughput                 : 0
update throughput             : 1128318
query throughput              : 10157384
total throughput              : 11285702

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=18.625
PAPI_L3_TCM=2.56832
PAPI_TOT_CYC=21501.8
PAPI_TOT_INS=8193.33
garbage=49217803200941
total_execution_walltime=16.096s
REALTIME_MAIN_END_PERF_FORMAT=6997693.215527083
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=16.23, faults_major=0, faults_minor=135781, mem_maxresident_kb=540848, user_cputime=1465.42, sys_cputime=27.21, percent_cpu=9191%
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=1