__step=000260
__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=data000260.txt
__path_data=data/data000260.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997693.427034004
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=348305 prefilling_elapsed_millis=1.001s
prefilling_size=375779 prefilling_elapsed_millis=1.101s
prefilling_size=402198 prefilling_elapsed_millis=1.201s
prefilling_size=427518 prefilling_elapsed_millis=1.301s
prefilling_size=451735 prefilling_elapsed_millis=1.402s
prefilling_size=475254 prefilling_elapsed_millis=1.502s
prefilling_size=497517 prefilling_elapsed_millis=1.602s
prefilling_size=518853 prefilling_elapsed_millis=1.702s
prefilling_size=539373 prefilling_elapsed_millis=1.802s
prefilling_size=558763 prefilling_elapsed_millis=1.902s
prefilling_size=577730 prefilling_elapsed_millis=2.003s
prefilling_size=595704 prefilling_elapsed_millis=2.103s
prefilling_size=613076 prefilling_elapsed_millis=2.203s
prefilling_size=629456 prefilling_elapsed_millis=2.303s
prefilling_size=645277 prefilling_elapsed_millis=2.403s
prefilling_size=660185 prefilling_elapsed_millis=2.504s
prefilling_size=674550 prefilling_elapsed_millis=2.604s
prefilling_size=688074 prefilling_elapsed_millis=2.704s
prefilling_size=701391 prefilling_elapsed_millis=2.804s
prefilling_size=714179 prefilling_elapsed_millis=2.904s
prefilling_size=726254 prefilling_elapsed_millis=3.005s
prefilling_size=737925 prefilling_elapsed_millis=3.105s
prefilling_size=749085 prefilling_elapsed_millis=3.205s
prefilling_size=759691 prefilling_elapsed_millis=3.305s
prefilling_size=769755 prefilling_elapsed_millis=3.405s
prefilling_size=779534 prefilling_elapsed_millis=3.506s
prefilling_size=788844 prefilling_elapsed_millis=3.606s
prefilling_size=797860 prefilling_elapsed_millis=3.706s
prefilling_size=806285 prefilling_elapsed_millis=3.806s
prefilling_size=814702 prefilling_elapsed_millis=3.906s
prefilling_size=822477 prefilling_elapsed_millis=4.007s
prefilling_size=829767 prefilling_elapsed_millis=4.107s
prefilling_size=836807 prefilling_elapsed_millis=4.207s
prefilling_size=843809 prefilling_elapsed_millis=4.307s
prefilling_size=850252 prefilling_elapsed_millis=4.407s
prefilling_size=856245 prefilling_elapsed_millis=4.508s
prefilling_size=862259 prefilling_elapsed_millis=4.608s
prefilling_size=868169 prefilling_elapsed_millis=4.708s
prefilling_size=873824 prefilling_elapsed_millis=4.808s
prefilling_size=879177 prefilling_elapsed_millis=4.908s
prefilling_size=884070 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 115917 keys with 96 threads...
timing_elapsed 0.268526s
prefilling_size=1000000 prefilling_elapsed_millis=0.269s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000056261959, performing 4582770 updates; total_prefilling_elapsed_ms=269 ms)
pref_size=1000000
pref_millis=269
prefill_elapsed_ms=269
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6997699.16165121
REALTIME_END_PERF_FORMAT=6997709.16246461

###############################################################################
################################## 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.01s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32751 64896 117235 156149 119253 43517 6817 318 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 15 552 10912 63775 147354 139752 54527 8696 386 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32766 65448 128147 219924 266607 183269 61344 9014 388 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32766 65448 128147 219924 266607 183269 61344 9014 388 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99988 1.99789 1.96643 1.80134 1.50226 1.24011 1.09727 1.03581 1.00777 1 1

tree_stats_height=25
tree_stats_numInternals=573705
tree_stats_numLeaves=425971
tree_stats_numNodes=999676
tree_stats_numKeys=999676

tree_stats_avgDegreeInternal=1.74249
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42611
tree_stats_avgKeyDepth=18.3341


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=128632
first_thread_announced_epoch_by_thread=2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788 2788
sum_commit_tx_reader_total=110048856
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 1162895 1164563 1179661 1116668 1095128 1119683 1118855 1181512 1110970 1120207 1118073 1191571 1122427 1119446 1109315 1106923 1110660 1113259 1184817 1117044 1194095 1167447 1177394 1195038 1176974 1117871 1183812 1178124 1182498 1173231 1117397 1190489 1188522 1125612 1174805 1179691 1184890 1171444 1164686 1196961 1180692 1191069 1165919 1180814 1194133 1186838 1185724 1182298 1168528 1174930 1194457 1166320 1107219 1113616 1106087 1118420 1159486 1195603 1126857 1183947 1119928 1116281 1124543 1106736 1121754 1118982 1116836 1171537 1111081 1105246 1109422 1118972 1110801 1180353 1111027 1126245 1183385 1196581 1169999 1110488 1170300 1095593 1162012 1160895 1113802 1095641 1100567 1188023 1183977 1123528 1114731 1088992 1111153 1113312 1117035 1091483
sum_commit_tx_writer_total=5791142
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 61149 61292 61730 58661 57689 58892 58404 62420 58274 59261 59044 62444 59377 58550 58388 58422 58066 58353 62067 58850 62883 61481 61885 62385 62236 58601 62355 61996 62259 61609 58868 62631 62480 58939 62097 61948 62535 62257 61680 62794 62030 62734 61533 62472 62605 62444 62441 62025 61485 62065 62803 61385 58253 58338 58355 58889 61152 62828 59128 62656 58785 58715 59047 58601 59256 59013 58810 61856 58035 58061 58243 58821 57995 62229 58666 59240 62116 63423 61783 58838 61429 57532 61115 61158 58426 58031 58011 62500 62161 59113 58823 56821 58767 58340 58880 57629
sum_abort_tx_total=3166
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 22 41 35 31 29 33 22 35 36 22 37 30 31 35 29 27 35 35 35 43 36 33 30 42 33 24 34 49 33 35 36 36 38 27 37 36 33 35 35 22 40 40 39 46 32 25 33 31 38 30 38 31 28 32 22 26 28 32 30 38 25 32 43 37 40 30 31 26 33 36 33 35 40 32 28 34 27 42 33 30 32 26 39 31 22 39 41 20 26 31 36 38 25 40 32 35
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=13.4:3 22.8:47 32.2:1447 41.6:30598 51:60528 60.4:2867 69.8:416 79.2:73 88.6:15 98:5
    [        4.00,        13.40]: 3
    (       13.40,        22.80]: 47
    (       22.80,        32.20]: 1447
    (       32.20,        41.60]: 30598
    (       41.60,        51.00]: 60528
    (       51.00,        60.40]: 2867
    (       60.40,        69.80]: 416
    (       69.80,        79.20]: 73
    (       79.20,        88.60]: 15
    (       88.60,        98.00]: 5

linear_histogram_of_none_tx_wrset_size_full_data=3.9:28915 6.8:6733 9.7:23064 12.6:11459 15.5:17095 18.4:6590 21.3:1393 24.2:680 27.1:63 30:6
    [        1.00,         3.90]: 28915
    (        3.90,         6.80]: 6733
    (        6.80,         9.70]: 23064
    (        9.70,        12.60]: 11459
    (       12.60,        15.50]: 17095
    (       15.50,        18.40]: 6590
    (       18.40,        21.30]: 1393
    (       21.30,        24.20]: 680
    (       24.20,        27.10]: 63
    (       27.10,        30.00]: 6
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=60962 61451 63071 63239 61960 61716 61757 60741 62868 62373 61889 62651 62016 62512 62897 61651 62358 62171 62065 61187 62518 62329 60671 62093 61368 61484 62264 62904 62361 62065 62043 61360 62912 62447 62146 62409 62042 62193 62657 62219 62219 62532 61119 61435 61466 62260 61381 61862 58277 58753 59170 59373 57855 57242 58693 58571 59076 58925 59063 58253 58389 58708 59017 58712 58567 57964 57408 57754 59245 58403 58531 58350 58728 58350 58755 58339 58463 57402 59097 59224 59029 58251 59066 58780 58473 58333 58745 58588 59173 58246 57709 57645 58960 58353 58626 58791
sum_num_inserts_total=5787719
sum_num_deletes_by_thread=61055 61321 62716 63103 61984 61721 62348 61342 62589 62134 62110 62490 62256 62794 62697 61824 62617 62457 61310 61046 62290 61734 61426 62081 61545 61403 62891 62481 62221 62289 62746 61492 62731 62879 61903 62486 61599 62661 63242 61861 61729 62672 61488 61036 61959 62424 61549 62087 58922 58647 58878 59588 58486 56953 58811 58393 59713 59303 58806 58347 58044 58400 59296 58965 58771 58653 57892 57903 58831 58837 59022 58488 58131 58607 58835 58832 58481 57650 58618 58476 59317 58891 59060 57720 58623 58506 58773 59408 59085 58816 57493 57469 58901 58590 58939 58481
sum_num_deletes_total=5794470
sum_num_searches_by_thread=1100036 1103594 1130951 1133662 1116176 1108345 1122779 1101044 1131966 1119816 1112903 1127979 1114938 1128497 1132837 1117916 1121628 1122797 1110018 1101811 1121359 1120694 1098541 1118548 1107100 1108842 1128860 1123897 1120919 1118932 1121349 1106076 1131335 1131934 1109652 1125628 1115638 1123311 1133856 1118502 1113047 1125798 1105098 1103384 1111415 1119248 1104522 1117690 1059273 1051926 1060527 1062843 1046966 1031618 1058142 1052956 1066696 1061240 1059440 1052196 1048904 1050557 1067672 1058588 1056216 1052627 1038372 1042921 1059041 1060756 1057443 1052278 1055369 1047485 1060203 1054783 1051782 1034060 1058179 1061013 1062664 1060117 1064515 1048972 1048249 1052854 1058397 1066555 1059737 1050641 1037923 1037703 1065729 1054709 1057764 1054340
sum_num_searches_total=104257809
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1222053 1226366 1256738 1260004 1240120 1231782 1246884 1223127 1257423 1244323 1236902 1253120 1239210 1253803 1258431 1241391 1246603 1247425 1233393 1224044 1246167 1244757 1220638 1242722 1230013 1231729 1254015 1249282 1245501 1243286 1246138 1228928 1256978 1257260 1233701 1250523 1239279 1248165 1259755 1242582 1236995 1251002 1227705 1225855 1234840 1243932 1227452 1241639 1176472 1169326 1178575 1181804 1163307 1145813 1175646 1169920 1185485 1179468 1177309 1168796 1165337 1167665 1185985 1176265 1173554 1169244 1153672 1158578 1177117 1177996 1174996 1169116 1172228 1164442 1177793 1171954 1168726 1149112 1175894 1178713 1181010 1177259 1182641 1165472 1165345 1169693 1175915 1184551 1177995 1167703 1153125 1152817 1183590 1171652 1175329 1171612
average_num_operations_total=1206666
stdev_num_operations_total=3769
sum_num_operations_total=115839998
min_num_operations_total=1145813
max_num_operations_total=1260004
first_time_thread_terminate_by_thread=10000142 10000080 10000080 10000084 10000085 10000084 10000083 10000084 10000085 10000085 10000084 10000085 10000086 10000080 10000083 10000085 10000080 10000081 10000080 10000081 10000085 10000081 10000081 10000080 10000083 10000080 10000084 10000086 10000084 10000085 10000083 10000080 10000084 10000085 10000085 10000084 10000086 10000080 10000085 10000085 10000080 10000087 10000080 10000084 10000085 10000081 10000080 10000080 10000082 10000081 10000084 10000084 10000083 10000083 10000087 10000083 10000084 10000083 10000082 10000084 10000083 10000084 10000082 10000087 10000086 10000082 10000083 10000086 10000086 10000084 10000081 10000086 10000082 10000081 10000083 10000082 10000085 10000083 10000087 10000081 10000083 10000083 10000082 10000086 10000083 10000085 10000089 10000083 10000086 10000082 10000106 10000083 10000083 10000083 10000081 10000084
min_time_thread_terminate_total=10000080
max_time_thread_terminate_total=10000142
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 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 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=1000190022474
threads_final_size=999676
final_keysum=1000190022474
final_size=999676
validate_result=success
Validation OK.
Structural validation OK.

total_find=104257809
total_rq=0
total_inserts=5787719
total_deletes=5794470
total_updates=11582189
total_queries=104257809
total_ops=115839998
find_throughput=10425780
rq_throughput=0
update_throughput=1158218
query_throughput=10425780
total_throughput=11583999


total find                    : 104257809
total rq                      : 0
total inserts                 : 5787719
total deletes                 : 5794470
total updates                 : 11582189
total queries                 : 104257809
total ops                     : 115839998
find throughput               : 10425780
rq throughput                 : 0
update throughput             : 1158218
query throughput              : 10425780
total throughput              : 11583999

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=18.5195
PAPI_L3_TCM=2.51568
PAPI_TOT_CYC=20957.3
PAPI_TOT_INS=8179.45
garbage=50006097305289
total_execution_walltime=16.054s
REALTIME_MAIN_END_PERF_FORMAT=6997709.497008253
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=16.22, faults_major=0, faults_minor=137300, mem_maxresident_kb=547052, user_cputime=1463.30, sys_cputime=26.23, percent_cpu=9181%
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