__step=000238
__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_int_bst_tm_auto.debra.rhnorec_post -nwork 144 -nprefill 144 -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=data000238.txt
__path_data=data/data000238.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997403.201642960
binary=./brown_int_bst_tm_auto.debra.rhnorec_post
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_int_bst_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=144
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=144
WORK_THREADS=144
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=32
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,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63,64,65,66,67,68,69,70,71,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167
RH noREC w/postfix htm system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.033s
prefilling_size=168858 prefilling_elapsed_millis=1.001s
prefilling_size=184128 prefilling_elapsed_millis=1.101s
prefilling_size=198774 prefilling_elapsed_millis=1.201s
prefilling_size=213511 prefilling_elapsed_millis=1.301s
prefilling_size=227936 prefilling_elapsed_millis=1.402s
prefilling_size=241797 prefilling_elapsed_millis=1.502s
prefilling_size=255424 prefilling_elapsed_millis=1.602s
prefilling_size=269090 prefilling_elapsed_millis=1.702s
prefilling_size=282163 prefilling_elapsed_millis=1.802s
prefilling_size=295271 prefilling_elapsed_millis=1.903s
prefilling_size=308336 prefilling_elapsed_millis=2.003s
prefilling_size=320875 prefilling_elapsed_millis=2.103s
prefilling_size=333308 prefilling_elapsed_millis=2.203s
prefilling_size=345535 prefilling_elapsed_millis=2.303s
prefilling_size=357480 prefilling_elapsed_millis=2.404s
prefilling_size=369030 prefilling_elapsed_millis=2.504s
prefilling_size=380728 prefilling_elapsed_millis=2.604s
prefilling_size=391997 prefilling_elapsed_millis=2.704s
prefilling_size=403041 prefilling_elapsed_millis=2.804s
prefilling_size=414056 prefilling_elapsed_millis=2.904s
prefilling_size=424808 prefilling_elapsed_millis=3.005s
prefilling_size=435160 prefilling_elapsed_millis=3.105s
prefilling_size=445367 prefilling_elapsed_millis=3.205s
prefilling_size=455366 prefilling_elapsed_millis=3.305s
prefilling_size=465551 prefilling_elapsed_millis=3.405s
prefilling_size=475318 prefilling_elapsed_millis=3.506s
prefilling_size=484728 prefilling_elapsed_millis=3.606s
prefilling_size=494045 prefilling_elapsed_millis=3.706s
prefilling_size=503248 prefilling_elapsed_millis=3.806s
prefilling_size=512275 prefilling_elapsed_millis=3.906s
prefilling_size=521104 prefilling_elapsed_millis=4.007s
prefilling_size=530025 prefilling_elapsed_millis=4.107s
prefilling_size=538681 prefilling_elapsed_millis=4.207s
prefilling_size=547195 prefilling_elapsed_millis=4.307s
prefilling_size=555504 prefilling_elapsed_millis=4.407s
prefilling_size=563469 prefilling_elapsed_millis=4.508s
prefilling_size=571283 prefilling_elapsed_millis=4.608s
prefilling_size=579328 prefilling_elapsed_millis=4.708s
prefilling_size=587173 prefilling_elapsed_millis=4.808s
prefilling_size=594704 prefilling_elapsed_millis=4.908s
prefilling_size=601909 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 398033 keys with 144 threads...
tid=0 thread_prefill_amount=4164 percent_done_estimate=150.6 elapsed_s=5 est_remaining_s=-4 / 1
tid=0 thread_prefill_amount=5552 percent_done_estimate=200.9 elapsed_s=6 est_remaining_s=-7 / -1
timing_elapsed 2.03202s
prefilling_size=1000000 prefilling_elapsed_millis=2.036s
finished prefilling to size 1000000 for expected size 1000000 keysum=999848488149, performing 2512160 updates; total_prefilling_elapsed_ms=2037 ms)
pref_size=1000000
pref_millis=2037
prefill_elapsed_ms=2037
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0
global_epoch_counter=15
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6997410.612158633
REALTIME_END_PERF_FORMAT=6997420.612347154
###############################################################################
################################## 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 (144 threads)...
partitioned into 939 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.016s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 250 480 913 1665 2936 4967 7976 12146 17583 23979 31350 38781 45775 51481 54865 55742 54439 50767 45424 38924 31885 25144 18996 13844 9511 6371 4171 2541 1470 849 487 276 157 75 37 15 7 2 1 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 3 10 26 97 214 482 1073 2018 3625 5958 8813 12718 16798 20971 25365 28397 29798 30389 29176 27077 23972 20012 16204 12531 9428 6534 4381 3017 1861 1086 616 369 210 128 57 29 8 5 1 0 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 253 490 939 1762 3150 5449 9049 14164 21208 29937 40163 51499 62573 72452 80230 84139 84237 81156 74600 66001 55857 45156 35200 26375 18939 12905 8552 5558 3331 1935 1103 645 367 203 94 44 15 7 2 1 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 253 490 939 1762 3150 5449 9049 14164 21208 29937 40163 51499 62573 72452 80230 84139 84237 81156 74600 66001 55857 45156 35200 26375 18939 12905 8552 5558 3331 1935 1103 645 367 203 94 44 15 7 2 1 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.98438 1.94862 1.93673 1.90415 1.84279 1.79778 1.74913 1.68383 1.63979 1.58252 1.5406 1.50168 1.46199 1.42633 1.3968 1.36488 1.33867 1.31716 1.29367 1.27583 1.25656 1.23759 1.22269 1.20963 1.19318 1.17921 1.169 1.16218 1.14214 1.1396 1.13127 1.14325 1.14109 1.12534 1.0936 1.07447 1 1 1 1 1 1
tree_stats_height=49
tree_stats_numInternals=656537
tree_stats_numLeaves=343459
tree_stats_numNodes=999996
tree_stats_numKeys=999996
tree_stats_avgDegreeInternal=1.52314
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34346
tree_stats_avgKeyDepth=23.8327
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0
global_epoch_counter=253
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:1 8:845 16:29879 32:3259
(2^02, 2^03]: 1
(2^03, 2^04]: 845
(2^04, 2^05]: 29879
(2^05, 2^06]: 3259
sum_limbo_reclamation_event_size_total=852118
count_limbo_reclamation_event_size_by_thread=236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236 236
count_limbo_reclamation_event_size_total=33984
sum_limbo_reclamation_event_count_total=34406
first_thread_announced_epoch_by_thread=506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506 506
sum_commit_tx_reader_total=
sum_commit_tx_reader_by_thread=
sum_commit_tx_writer_total=
sum_commit_tx_writer_by_thread=
sum_abort_tx_total=
sum_abort_tx_by_thread=
max_ht_capacity_by_thread=
linear_histogram_of_none_tx_rdset_size_full_data=
linear_histogram_of_none_tx_wrset_size_full_data=
sum_fasthtm_commit_total=32780252
sum_fasthtm_abort_total=72614674
sum_htmpostfix_commit_total=1703353
sum_htmpostfix_abort_total=1670124
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=50334903
sum_stmprefix_commit_total=229
sum_slow_commit_total=16704
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=11875 12078 12299 10416 12377 12168 12040 12002 12191 9638 12355 12555 12227 12299 12421 10535 12211 11970 12179 12183 11914 10436 12391 12223 11861 12110 12173 10430 12466 12341 12263 12014 12243 9825 12370 12262 12059 12154 12606 10258 12155 12042 12189 12248 12048 10253 12474 12341 12643 13232 13019 10953 13035 12616 13007 12654 13294 10755 12684 12851 12902 13134 13008 10389 12689 13007 12475 12957 13103 10774 12918 13208 12643 12994 12767 11133 13003 12650 12993 12745 12983 10819 13069 13018 12883 12886 12812 10593 12681 13258 12626 12497 12966 10843 13137 13141 11930 11816 11872 9514 9432 12324 11840 11801 12321 9718 12291 12178 12050 11878 11737 9594 11881 12320 11862 11983 12007 9491 12344 12127 11972 11800 12208 9805 9512 12445 11956 12001 12247 9526 12145 12339 11927 11934 11968 9438 11955 12348 11840 12037 11740 9528 12125 12076
sum_num_inserts_total=1725798
sum_num_deletes_by_thread=11949 12030 12320 10365 12205 12348 12005 12017 12045 9861 12408 12410 12003 12215 12404 10351 12247 12192 12501 11979 12159 10299 12226 12349 11835 12334 12088 10324 12229 12060 12172 11976 12123 9731 12375 12216 12153 12020 12723 10407 12457 11851 12378 12197 11924 10122 12483 12148 12664 12884 13264 10903 12901 12845 12732 12916 13058 10757 12856 12877 12869 13199 12867 10499 12659 13047 12900 12821 13152 10970 12969 13060 12786 12742 12883 10784 12875 12875 12927 12777 13038 10732 13075 12687 12795 12846 12822 10372 12673 13148 12657 12727 12952 10865 13193 12934 11914 11687 11925 9637 9369 12265 11947 11726 12204 9697 12098 12304 12377 12275 11774 9423 12034 12073 11854 12089 12121 9496 12536 12179 11901 11860 11835 9521 9459 12220 12068 11868 12099 9490 12273 12187 11968 12046 11998 9496 11876 12157 11961 11935 11790 9601 12165 11907
sum_num_deletes_total=1723733
sum_num_searches_by_thread=212330 215498 220989 187239 220799 219736 214512 215094 217981 175257 224565 224490 221010 219433 224506 187281 220866 216328 219001 219398 217721 184235 220664 219767 214512 219312 221191 189050 222602 217792 220865 215435 220793 176353 224577 221726 217285 219433 227660 184848 220930 216585 219777 219622 212970 183336 222488 221589 226208 235443 238574 194619 232606 229636 232197 229700 235059 194261 229346 232463 232483 235533 231699 188188 228996 235813 226737 231485 236040 195765 233937 236459 228889 233366 229959 200033 231968 232264 232394 230826 233150 192535 235739 231728 228596 231107 232418 191221 227417 236847 225558 228763 236206 196467 234592 234648 214464 212142 215964 172215 169434 223874 215415 213917 221035 173253 218006 219906 220006 217336 211718 171745 214470 220032 212643 216494 217657 170574 224137 219429 215095 211822 216304 173340 170361 221227 216769 213127 218517 170527 222994 221475 215160 215468 216534 169455 214926 223317 213799 216535 208918 171993 221458 216651
sum_num_searches_total=31051007
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=236154 239606 245608 208020 245381 244252 238557 239113 242217 194756 249328 249455 245240 243947 249331 208167 245324 240490 243681 243560 241794 204970 245281 244339 238208 243756 245452 209804 247297 242193 245300 239425 245159 195909 249322 246204 241497 243607 252989 205513 245542 240478 244344 244067 236942 203711 247445 246078 251515 261559 264857 216475 258542 255097 257936 255270 261411 215773 254886 258191 258254 261866 257574 209076 254344 261867 252112 257263 262295 217509 259824 262727 254318 259102 255609 221950 257846 257789 258314 256348 259171 214086 261883 257433 254274 256839 258052 212186 252771 263253 250841 253987 262124 218175 260922 260723 238308 235645 239761 191366 188235 248463 239202 237444 245560 192668 242395 244388 244433 241489 235229 190762 238385 244425 236359 240566 241785 189561 249017 243735 238968 235482 240347 192666 189332 245892 240793 236996 242863 189543 247412 246001 239055 239448 240500 188389 238757 247822 237600 240507 232448 191122 245748 240634
average_num_operations_total=239587
stdev_num_operations_total=1645
sum_num_operations_total=34500538
min_num_operations_total=188235
max_num_operations_total=264857
first_time_thread_terminate_by_thread=10000865 10000635 10000206 10000631 10000867 10000885 10000879 10000435 10000877 10000864 10000285 10000581 10000882 10000596 10000208 10000880 10000874 10000878 10000465 10000882 10000193 10000378 10000628 10000620 10000869 10000884 10000639 10000512 10000413 10000435 10000884 10000631 10000631 10000595 10000636 10000621 10000519 10000573 10000520 10000216 10000188 10000881 10000876 10000481 10000569 10000637 10000880 10000476 10000379 10000336 10000610 10000442 10000262 10000437 10000586 10000481 10000585 10000571 10000211 10000400 10000618 10000531 10000356 10000274 10000337 10000558 10000611 10000292 10000545 10000508 10000194 10000195 10000549 10000438 10000358 10000639 10000426 10000341 10000225 10000268 10000541 10000599 10000504 10000264 10000284 10000548 10000422 10000432 10000492 10000195 10000618 10000403 10000524 10000197 10000350 10000511 10000456 10000218 10000292 10000509 10000487 10000570 10000612 10000517 10000496 10000338 10000192 10000186 10000226 10000523 10000395 10000558 10000446 10000622 10000540 10000192 10000474 10000357 10000495 10000198 10000211 10000516 10000366 10000188 10000608 10000603 10000871 10000475 10000255 10000240 10000449 10000413 10000551 10000340 10000518 10000599 10000358 10000186 10000505 10000527 10000202 10000614 10000228 10000491
min_time_thread_terminate_total=10000186
max_time_thread_terminate_total=10000885
first_time_thread_start_by_thread=8 5 5 5 5 4 5 4 5 5 4 5 5 5 5 5 5 4 4 5 5 5 5 4 4 5 5 5 5 4 5 4 5 5 4 5 5 5 5 5 5 4 4 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 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=8
sum_duration_all_ops_total=
threads_final_keysum=999918239376
threads_final_size=999996
final_keysum=999918239376
final_size=999996
validate_result=success
Validation OK.
Structural validation OK.
total_find=31051007
total_rq=0
total_inserts=1725798
total_deletes=1723733
total_updates=3449531
total_queries=31051007
total_ops=34500538
find_throughput=3105100
rq_throughput=0
update_throughput=344953
query_throughput=3105100
total_throughput=3450053
total find : 31051007
total rq : 0
total inserts : 1725798
total deletes : 1723733
total updates : 3449531
total queries : 31051007
total ops : 34500538
find throughput : 3105100
rq throughput : 0
update throughput : 344953
query throughput : 3105100
total throughput : 3450053
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=30.5102
PAPI_L3_TCM=8.37271
PAPI_TOT_CYC=106634
PAPI_TOT_INS=3836.32
garbage=15527211061325
total_execution_walltime=17.55s
REALTIME_MAIN_END_PERF_FORMAT=6997420.753258033
RH noREC w/postfix htm system shutdown:
HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=17.69, faults_major=0, faults_minor=120393, mem_maxresident_kb=478208, user_cputime=2452.22, sys_cputime=27.69, percent_cpu=14015%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.rhnorec_post
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2