__step=000076
__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.hytm1 -nwork 96 -nprefill 96 -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=data000076.txt
__path_data=data/data000076.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6993890.597798701
binary=./brown_sigouin_int_avl_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_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=20000000
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


HyTM1 system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.017s
prefilling_size=1310298 prefilling_elapsed_millis=1.001s
prefilling_size=1433148 prefilling_elapsed_millis=1.101s
prefilling_size=1554433 prefilling_elapsed_millis=1.201s
prefilling_size=1703816 prefilling_elapsed_millis=1.301s
prefilling_size=1826219 prefilling_elapsed_millis=1.402s
prefilling_size=1943225 prefilling_elapsed_millis=1.502s
prefilling_size=2060004 prefilling_elapsed_millis=1.602s
prefilling_size=2174820 prefilling_elapsed_millis=1.702s
prefilling_size=2298629 prefilling_elapsed_millis=1.802s
prefilling_size=2421051 prefilling_elapsed_millis=1.903s
prefilling_size=2532722 prefilling_elapsed_millis=2.003s
prefilling_size=2643487 prefilling_elapsed_millis=2.103s
prefilling_size=2755468 prefilling_elapsed_millis=2.203s
prefilling_size=2878299 prefilling_elapsed_millis=2.303s
prefilling_size=3034596 prefilling_elapsed_millis=2.404s
prefilling_size=3155835 prefilling_elapsed_millis=2.504s
prefilling_size=3262158 prefilling_elapsed_millis=2.604s
prefilling_size=3365359 prefilling_elapsed_millis=2.704s
prefilling_size=3466683 prefilling_elapsed_millis=2.804s
prefilling_size=3567002 prefilling_elapsed_millis=2.905s
prefilling_size=3666284 prefilling_elapsed_millis=3.005s
prefilling_size=3764939 prefilling_elapsed_millis=3.105s
prefilling_size=3860850 prefilling_elapsed_millis=3.205s
prefilling_size=3957317 prefilling_elapsed_millis=3.306s
prefilling_size=4053018 prefilling_elapsed_millis=3.406s
prefilling_size=4146798 prefilling_elapsed_millis=3.506s
prefilling_size=4240187 prefilling_elapsed_millis=3.606s
prefilling_size=4331957 prefilling_elapsed_millis=3.706s
prefilling_size=4422596 prefilling_elapsed_millis=3.807s
prefilling_size=4511712 prefilling_elapsed_millis=3.907s
prefilling_size=4600432 prefilling_elapsed_millis=4.007s
prefilling_size=4687997 prefilling_elapsed_millis=4.107s
prefilling_size=4774908 prefilling_elapsed_millis=4.207s
prefilling_size=4860436 prefilling_elapsed_millis=4.308s
prefilling_size=4945164 prefilling_elapsed_millis=4.408s
prefilling_size=5028359 prefilling_elapsed_millis=4.508s
prefilling_size=5111158 prefilling_elapsed_millis=4.608s
prefilling_size=5192760 prefilling_elapsed_millis=4.708s
prefilling_size=5273143 prefilling_elapsed_millis=4.809s
prefilling_size=5351976 prefilling_elapsed_millis=4.909s
prefilling_size=5430201 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 4569714 keys with 96 threads...
tid=0 thread_prefill_amount=60407 percent_done_estimate=126.9 elapsed_s=5 est_remaining_s=-2 / 3
tid=0 thread_prefill_amount=62490 percent_done_estimate=131.3 elapsed_s=5 est_remaining_s=-3 / 2
tid=0 thread_prefill_amount=64573 percent_done_estimate=135.7 elapsed_s=5 est_remaining_s=-3 / 2
tid=0 thread_prefill_amount=66656 percent_done_estimate=140.0 elapsed_s=6 est_remaining_s=-4 / 2
tid=0 thread_prefill_amount=68739 percent_done_estimate=144.4 elapsed_s=6 est_remaining_s=-4 / 2
tid=0 thread_prefill_amount=70822 percent_done_estimate=148.8 elapsed_s=6 est_remaining_s=-4 / 2
tid=0 thread_prefill_amount=72905 percent_done_estimate=153.2 elapsed_s=6 est_remaining_s=-5 / 1
tid=0 thread_prefill_amount=74988 percent_done_estimate=157.5 elapsed_s=6 est_remaining_s=-5 / 1
tid=0 thread_prefill_amount=77071 percent_done_estimate=161.9 elapsed_s=6 est_remaining_s=-5 / 1
tid=0 thread_prefill_amount=79154 percent_done_estimate=166.3 elapsed_s=7 est_remaining_s=-6 / 1
tid=0 thread_prefill_amount=81237 percent_done_estimate=170.7 elapsed_s=7 est_remaining_s=-6 / 0
tid=0 thread_prefill_amount=83320 percent_done_estimate=175.0 elapsed_s=7 est_remaining_s=-7 / 0
tid=0 thread_prefill_amount=85403 percent_done_estimate=179.4 elapsed_s=7 est_remaining_s=-7 / 0
tid=0 thread_prefill_amount=87486 percent_done_estimate=183.8 elapsed_s=7 est_remaining_s=-7 / -0
tid=0 thread_prefill_amount=89569 percent_done_estimate=188.2 elapsed_s=7 est_remaining_s=-8 / -0
tid=0 thread_prefill_amount=91652 percent_done_estimate=192.5 elapsed_s=7 est_remaining_s=-8 / -1
tid=0 thread_prefill_amount=93735 percent_done_estimate=196.9 elapsed_s=7 est_remaining_s=-8 / -1
tid=0 thread_prefill_amount=95818 percent_done_estimate=201.3 elapsed_s=8 est_remaining_s=-9 / -1
tid=0 thread_prefill_amount=97901 percent_done_estimate=205.7 elapsed_s=8 est_remaining_s=-9 / -1
tid=0 thread_prefill_amount=99984 percent_done_estimate=210.0 elapsed_s=8 est_remaining_s=-9 / -1
tid=0 thread_prefill_amount=102067 percent_done_estimate=214.4 elapsed_s=8 est_remaining_s=-10 / -2
tid=0 thread_prefill_amount=104150 percent_done_estimate=218.8 elapsed_s=8 est_remaining_s=-10 / -2
tid=0 thread_prefill_amount=106233 percent_done_estimate=223.2 elapsed_s=8 est_remaining_s=-10 / -2
timing_elapsed 3.1899s
prefilling_size=10000000 prefilling_elapsed_millis=3.197s
finished prefilling to size 10000000 for expected size 10000000 keysum=99986386340639, performing 23429471 updates; total_prefilling_elapsed_ms=3197 ms)
pref_size=10000000
pref_millis=3197
prefill_elapsed_ms=3197
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6993899.112059340
REALTIME_END_PERF_FORMAT=6993909.112148819

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

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262043 519564 965869 1436311 1381469 714756 173149 16949 399 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 92 4096 63265 407206 1158239 1503965 874336 219015 21342 506
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262135 523660 1029134 1843517 2539708 2218721 1047485 235964 21741 506
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262135 523660 1029134 1843517 2539708 2218721 1047485 235964 21741 506
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99994 1.99802 1.97309 1.8528 1.59853 1.32966 1.14996 1.05997 1.02031 1.00492 1

tree_stats_height=28
tree_stats_numInternals=5732651
tree_stats_numLeaves=4252063
tree_stats_numNodes=9984714
tree_stats_numKeys=9984714

tree_stats_avgDegreeInternal=1.74173
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42586
tree_stats_avgKeyDepth=21.7012


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=277727
first_thread_announced_epoch_by_thread=6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344 6344
sum_num_inserts_by_thread=133108 133710 135593 131872 132712 131485 133502 133525 134459 135394 133411 132770 132062 134228 134056 134299 135143 133023 132107 132736 133286 134726 132350 131250 133500 133585 134013 134333 132600 132426 132969 133237 135000 135208 132716 133248 132150 134226 134195 134487 134413 133180 132064 133672 133696 135532 132961 131136 130897 134258 134569 134250 132689 132672 132541 133950 134079 134728 134844 130716 132299 133165 134535 134725 133723 132902 131916 132506 134886 135340 133933 131600 130707 134518 135085 134783 133324 133685 133071 132827 134937 134685 134456 132342 133890 133230 133403 134717 132733 132151 132527 132197 134419 134386 131408 131973
sum_num_inserts_total=12808531
sum_num_deletes_by_thread=133415 133356 134726 131809 133609 132261 132945 133088 134930 135503 133974 133632 133295 134215 134716 134357 135283 133206 132917 131570 133225 134871 132591 130847 133263 132992 134287 133607 132370 132035 132800 132869 135116 135211 133006 132398 133059 133842 133965 133639 134623 133295 132122 132844 133739 135291 133166 131730 131573 134707 134585 133802 133577 132612 132500 133776 134381 135906 134303 131122 133286 133394 134481 135458 134059 133027 132271 132979 135009 135235 133394 131109 130660 134502 135000 135064 133564 134266 132158 132769 134560 134170 135054 131792 133996 133842 133878 134053 133285 132560 132648 132598 134286 133820 132162 132372
sum_num_deletes_total=12815215
sum_num_searches_by_thread=2396460 2409603 2433819 2362660 2396074 2375120 2400605 2395842 2425014 2434263 2407492 2401010 2387479 2420328 2426644 2427345 2422539 2393942 2380505 2378165 2403518 2417790 2387058 2363662 2397803 2396983 2420799 2407367 2388471 2379391 2398127 2401200 2436142 2438389 2394073 2390503 2392238 2414816 2417973 2416502 2426184 2407044 2382191 2397581 2398725 2431342 2398648 2365592 2368020 2418309 2422564 2413863 2407324 2391082 2380774 2404669 2418740 2437953 2420396 2359794 2396297 2394277 2421319 2430940 2417805 2383381 2381404 2389031 2421459 2432890 2420372 2367435 2347786 2422988 2436664 2429148 2407030 2406098 2380691 2393405 2415302 2419661 2422972 2389272 2410744 2404483 2393459 2417605 2388629 2376975 2388399 2386634 2426496 2419402 2369876 2387839
sum_num_searches_total=230686677
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2662983 2676669 2704138 2626341 2662395 2638866 2667052 2662455 2694403 2705160 2674877 2667412 2652836 2688771 2695416 2696001 2692965 2660171 2645529 2642471 2670029 2687387 2651999 2625759 2664566 2663560 2689099 2675307 2653441 2643852 2663896 2667306 2706258 2708808 2659795 2656149 2657447 2682884 2686133 2684628 2695220 2673519 2646377 2664097 2666160 2702165 2664775 2628458 2630490 2687274 2691718 2681915 2673590 2656366 2645815 2672395 2687200 2708587 2689543 2621632 2661882 2660836 2690335 2701123 2685587 2649310 2645591 2654516 2691354 2703465 2687699 2630144 2609153 2692008 2706749 2698995 2673918 2674049 2645920 2659001 2684799 2688516 2692482 2653406 2678630 2671555 2660740 2686375 2654647 2641686 2653574 2651429 2695201 2687608 2633446 2652184
average_num_operations_total=2669900
stdev_num_operations_total=2341
sum_num_operations_total=256310423
min_num_operations_total=2609153
max_num_operations_total=2708808
first_time_thread_terminate_by_thread=10000167 10000092 10000091 10000093 10000089 10000089 10000091 10000091 10000091 10000090 10000093 10000093 10000091 10000092 10000089 10000092 10000089 10000093 10000092 10000092 10000090 10000090 10000092 10000092 10000092 10000089 10000091 10000093 10000091 10000092 10000089 10000089 10000091 10000090 10000093 10000089 10000091 10000092 10000093 10000092 10000092 10000089 10000092 10000092 10000090 10000092 10000092 10000092 10000091 10000090 10000092 10000089 10000090 10000090 10000090 10000090 10000090 10000091 10000089 10000090 10000091 10000092 10000091 10000089 10000090 10000091 10000091 10000090 10000089 10000090 10000090 10000090 10000091 10000089 10000092 10000090 10000090 10000090 10000090 10000090 10000090 10000091 10000090 10000090 10000091 10000092 10000091 10000089 10000090 10000091 10000091 10000090 10000089 10000090 10000090 10000090
min_time_thread_terminate_total=10000089
max_time_thread_terminate_total=10000167
first_time_thread_start_by_thread=10 4 4 5 5 4 4 5 4 5 5 5 5 4 5 5 4 4 4 4 5 5 5 4 3 4 4 5 5 4 4 5 4 5 5 5 5 4 5 5 4 4 4 4 5 5 5 4 5 4 5 5 5 5 5 5 5 5 5 5 4 4 5 5 4 5 5 5 5 5 4 5 5 4 5 5 5 5 4 5 5 5 5 5 4 4 5 5 4 5 5 5 5 5 4 5
min_time_thread_start_total=3
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=99828948623709
threads_final_size=9984714
final_keysum=99828948623709
final_size=9984714
validate_result=success
Validation OK.
Structural validation OK.

total_find=230686677
total_rq=0
total_inserts=12808531
total_deletes=12815215
total_updates=25623746
total_queries=230686677
total_ops=256310423
find_throughput=23068667
rq_throughput=0
update_throughput=2562374
query_throughput=23068667
total_throughput=25631042


total find                    : 230686677
total rq                      : 0
total inserts                 : 12808531
total deletes                 : 12815215
total updates                 : 25623746
total queries                 : 230686677
total ops                     : 256310423
find throughput               : 23068667
rq throughput                 : 0
update throughput             : 2562374
query throughput              : 23068667
total throughput              : 25631042

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=21.7912
PAPI_L3_TCM=7.58552
PAPI_TOT_CYC=10303.2
PAPI_TOT_INS=8403.28
garbage=1154561938670886
total_execution_walltime=18.646s
REALTIME_MAIN_END_PERF_FORMAT=6993909.261547131
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 279459065
[slow htm]
[fallback]
total fallback commit         : 280830
seconds global lock is held   : 12.1156
[time_cmd_output] time_elapsed_sec=18.85, faults_major=0, faults_minor=495794, mem_maxresident_kb=1198540, user_cputime=1751.93, sys_cputime=12.10, percent_cpu=9356%
timeout=false
algorithm=brown_sigouin_int_avl_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=2