__step=000251
__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.hytm1 -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=data000251.txt
__path_data=data/data000251.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997575.990169018
binary=./brown_int_bst_tm_auto.debra.hytm1
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=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=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
HyTM1 system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.016s
prefilling_size=998906 prefilling_elapsed_millis=1.001s
finished prefilling to size 998853 for expected size 1000000 keysum=999376722528, performing 16576504 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=998853
pref_millis=1102
prefill_elapsed_ms=1127
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=200
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6997577.304170867
REALTIME_END_PERF_FORMAT=6997587.304250345
###############################################################################
################################## 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 (192 threads)...
partitioned into 923 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.04s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 125 246 476 887 1585 2783 4629 7453 11409 16339 22249 28940 35671 42023 47174 50663 52245 51677 49463 45631 40655 34787 29059 23329 17844 13003 9255 6146 3762 2196 1223 708 423 253 157 114 75 40 13 9 5 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 2 3 8 36 105 211 519 989 1903 3557 5686 8403 11889 15652 19628 23269 25939 28026 28591 28097 26631 24491 21213 18228 14964 11763 8680 6410 4439 2743 1640 878 510 305 187 101 72 57 36 6 5 5
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 127 249 484 923 1690 2994 5148 8442 13312 19896 27935 37343 47560 57675 66802 73932 78184 79703 78054 73728 67286 59278 50272 41557 32808 24766 17935 12556 8201 4939 2863 1586 933 558 344 215 147 97 49 15 10 5
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 127 249 484 923 1690 2994 5148 8442 13312 19896 27935 37343 47560 57675 66802 73932 78184 79703 78054 73728 67286 59278 50272 41557 32808 24766 17935 12556 8201 4939 2863 1586 933 558 344 215 147 97 49 15 10 5
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.98438 1.97638 1.95582 1.92355 1.86999 1.83373 1.78991 1.74068 1.69403 1.63755 1.58283 1.54033 1.49862 1.46266 1.42963 1.40056 1.37225 1.3512 1.33094 1.31087 1.29371 1.27677 1.26123 1.24861 1.2281 1.21099 1.19914 1.18405 1.16367 1.14352 1.13505 1.12679 1.14187 1.14469 1.16308 1.1686 1.15349 1.14966 1.09278 1.04082 1.06667 1 1
tree_stats_height=49
tree_stats_numInternals=654851
tree_stats_numLeaves=345877
tree_stats_numNodes=1000728
tree_stats_numKeys=1000728
tree_stats_avgDegreeInternal=1.52818
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34563
tree_stats_avgKeyDepth=24.3065
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=8185
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:4 2:78 4:2774 8:80523 16:663571 32:19418
[2^00, 2^01]: 4
(2^01, 2^02]: 78
(2^02, 2^03]: 2774
(2^03, 2^04]: 80523
(2^04, 2^05]: 663571
(2^05, 2^06]: 19418
sum_limbo_reclamation_event_size_total=16541375
count_limbo_reclamation_event_size_by_thread=7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983 7983
count_limbo_reclamation_event_size_total=766368
sum_limbo_reclamation_event_count_total=766560
first_thread_announced_epoch_by_thread=16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370 16370
sum_num_inserts_by_thread=368143 261885 352346 358162 356753 360785 360037 335470 361796 366047 366004 356567 357955 342144 365846 352653 361760 307559 355270 337203 355940 351441 343327 342982 363999 220236 312207 371261 364729 339312 346061 340478 348569 369772 363965 321250 342291 350382 360481 343167 180198 322754 358267 341044 349865 369948 355200 359686 339488 357081 367787 338776 348327 356219 360936 356371 351826 364972 368795 348319 361623 367551 372524 362701 370099 367903 275397 289287 330162 368579 334191 305514 335506 368328 373988 371618 360374 372405 359941 351921 336833 318161 366916 373974 363358 377085 373022 351541 341557 367921 170819 295906 318103 349120 341437 325118
sum_num_inserts_total=33102577
sum_num_deletes_by_thread=366385 260535 351937 357620 356000 362539 360351 335178 362173 366462 366179 357584 357540 341209 364847 351362 361769 307564 355242 337779 357097 351232 342502 342513 363008 219318 313396 371669 364311 338468 345253 338122 347896 370324 363625 322791 343017 350299 360037 343088 180400 322955 358630 341375 348944 370096 355665 359318 339892 357282 367494 338668 348103 355444 360480 356505 352561 364112 369820 347921 360377 367344 373248 363462 368936 368257 275032 289381 331103 367971 332985 304996 335061 367146 374727 369666 362414 373562 358611 350148 337776 318450 368612 373068 364616 377090 373620 350587 342068 368065 170756 294804 318276 349361 341128 326243
sum_num_deletes_total=33092833
sum_num_searches_by_thread=6611983 4692933 6330068 6439231 6409018 6490405 6490073 6036009 6499991 6592332 6585957 6423036 6448669 6168121 6589221 6354889 6503190 5545729 6393550 6081068 6407721 6324065 6170167 6172052 6548000 3957580 5631331 6673918 6552825 6112579 6230771 6104045 6257860 6663540 6538964 5806281 6167965 6308424 6496287 6180377 3250502 5822581 6449574 6145172 6293933 6646185 6395974 6474073 6112336 6424056 6616413 6116166 6261863 6399944 6499177 6436092 6329899 6558041 6644227 6261600 6484957 6616663 6703000 6526559 6654261 6611904 4953530 5222331 5938754 6640522 6004195 5486972 6041183 6625846 6748051 6678069 6497878 6717732 6446073 6312298 6064614 5736967 6616265 6713543 6539397 6787350 6714344 6321150 6161621 6622757 3068771 5310211 5734628 6290575 6130291 5852935
sum_num_searches_total=595704230
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=7346511 5215353 7034351 7155013 7121771 7213729 7210461 6706657 7223960 7324841 7318140 7137187 7164164 6851474 7319914 7058904 7226719 6160852 7104062 6756050 7120758 7026738 6855996 6857547 7275007 4397134 6256934 7416848 7281865 6790359 6922085 6782645 6954325 7403636 7266554 6450322 6853273 7009105 7216805 6866632 3611100 6468290 7166471 6827591 6992742 7386229 7106839 7193077 6791716 7138419 7351694 6793610 6958293 7111607 7220593 7148968 7034286 7287125 7382842 6957840 7206957 7351558 7448772 7252722 7393296 7348064 5503959 5800999 6600019 7377072 6671371 6097482 6711750 7361320 7496766 7419353 7220666 7463699 7164625 7014367 6739223 6373578 7351793 7460585 7267371 7541525 7460986 7023278 6845246 7358743 3410346 5900921 6371007 6989056 6812856 6504296
average_num_operations_total=6894787
stdev_num_operations_total=72585
sum_num_operations_total=661899640
min_num_operations_total=3410346
max_num_operations_total=7541525
first_time_thread_terminate_by_thread=10000080 10000089 10000079 10000080 10000079 10000079 10000081 10000079 10000079 10000081 10000082 10000081 10000080 10000079 10000081 10000080 10000079 10000081 10000079 10000079 10000082 10000079 10000079 10000081 10000080 10000089 10000080 10000080 10000082 10000140 10000081 10000079 10000079 10000081 10000082 10000081 10000080 10000079 10000081 10000080 10000079 10000081 10000080 10000079 10000082 10000079 10000079 10000081 10000080 10000082 10000080 10000081 10000080 10000081 10000082 10000079 10000081 10000080 10000081 10000080 10000082 10000081 10000080 10000080 10000080 10000080 10000081 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000081 10000080 10000081 10000081 10000079 10000081 10000080 10000081 10000080 10000082 10000081 10000080 10000080 10000080 10000080 10000081 10000080 10000080 10000080 10000080 10000080
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10000140
first_time_thread_start_by_thread=6 6 6 6 5 5 5 5 6 6 6 6 5 5 5 6 6 6 6 6 6 6 6 6 6 6 6 6 5 10 5 5 6 6 6 6 5 5 5 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6
min_time_thread_start_total=5
max_time_thread_start_total=10
sum_duration_all_ops_total=
threads_final_keysum=1000326703620
threads_final_size=1000728
final_keysum=1000326703620
final_size=1000728
validate_result=success
Validation OK.
Structural validation OK.
total_find=595704230
total_rq=0
total_inserts=33102577
total_deletes=33092833
total_updates=66195410
total_queries=595704230
total_ops=661899640
find_throughput=59570423
rq_throughput=0
update_throughput=6619541
query_throughput=59570423
total_throughput=66189964
total find : 595704230
total rq : 0
total inserts : 33102577
total deletes : 33092833
total updates : 66195410
total queries : 595704230
total ops : 661899640
find throughput : 59570423
rq throughput : 0
update throughput : 6619541
query throughput : 59570423
total throughput : 66189964
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=8.96024
PAPI_L3_TCM=0.514594
PAPI_TOT_CYC=3976.56
PAPI_TOT_INS=1931.23
garbage=297907809779874
total_execution_walltime=11.541s
REALTIME_MAIN_END_PERF_FORMAT=6997587.546117419
HyTM1 system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 677262216
[slow htm]
[fallback]
total fallback commit : 1213929
seconds global lock is held : 1.4981
[time_cmd_output] time_elapsed_sec=11.69, faults_major=0, faults_minor=100622, mem_maxresident_kb=365832, user_cputime=1094.03, sys_cputime=3.19, percent_cpu=9384%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.hytm1
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1