__step=000075
__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=data000075.txt
__path_data=data/data000075.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6993871.700823174
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.016s
prefilling_size=1303002 prefilling_elapsed_millis=1.001s
prefilling_size=1426100 prefilling_elapsed_millis=1.101s
prefilling_size=1547755 prefilling_elapsed_millis=1.201s
prefilling_size=1687360 prefilling_elapsed_millis=1.301s
prefilling_size=1820206 prefilling_elapsed_millis=1.401s
prefilling_size=1936993 prefilling_elapsed_millis=1.502s
prefilling_size=2053618 prefilling_elapsed_millis=1.602s
prefilling_size=2169693 prefilling_elapsed_millis=1.702s
prefilling_size=2293873 prefilling_elapsed_millis=1.802s
prefilling_size=2417879 prefilling_elapsed_millis=1.902s
prefilling_size=2530696 prefilling_elapsed_millis=2.003s
prefilling_size=2642347 prefilling_elapsed_millis=2.103s
prefilling_size=2753617 prefilling_elapsed_millis=2.203s
prefilling_size=2876928 prefilling_elapsed_millis=2.303s
prefilling_size=3032165 prefilling_elapsed_millis=2.403s
prefilling_size=3156786 prefilling_elapsed_millis=2.504s
prefilling_size=3262544 prefilling_elapsed_millis=2.604s
prefilling_size=3367445 prefilling_elapsed_millis=2.704s
prefilling_size=3469913 prefilling_elapsed_millis=2.804s
prefilling_size=3571658 prefilling_elapsed_millis=2.904s
prefilling_size=3671971 prefilling_elapsed_millis=3.005s
prefilling_size=3770797 prefilling_elapsed_millis=3.105s
prefilling_size=3868526 prefilling_elapsed_millis=3.205s
prefilling_size=3965661 prefilling_elapsed_millis=3.305s
prefilling_size=4061235 prefilling_elapsed_millis=3.405s
prefilling_size=4156152 prefilling_elapsed_millis=3.505s
prefilling_size=4250898 prefilling_elapsed_millis=3.606s
prefilling_size=4343824 prefilling_elapsed_millis=3.706s
prefilling_size=4435399 prefilling_elapsed_millis=3.806s
prefilling_size=4525167 prefilling_elapsed_millis=3.906s
prefilling_size=4614556 prefilling_elapsed_millis=4.006s
prefilling_size=4703287 prefilling_elapsed_millis=4.107s
prefilling_size=4790498 prefilling_elapsed_millis=4.207s
prefilling_size=4876778 prefilling_elapsed_millis=4.307s
prefilling_size=4961788 prefilling_elapsed_millis=4.407s
prefilling_size=5046832 prefilling_elapsed_millis=4.507s
prefilling_size=5131213 prefilling_elapsed_millis=4.608s
prefilling_size=5214711 prefilling_elapsed_millis=4.708s
prefilling_size=5297136 prefilling_elapsed_millis=4.808s
prefilling_size=5377426 prefilling_elapsed_millis=4.908s
prefilling_size=5456794 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 4543125 keys with 96 threads...
tid=0 thread_prefill_amount=56241 percent_done_estimate=118.8 elapsed_s=5 est_remaining_s=-2 / 3
tid=0 thread_prefill_amount=58324 percent_done_estimate=123.2 elapsed_s=5 est_remaining_s=-2 / 3
tid=0 thread_prefill_amount=60407 percent_done_estimate=127.6 elapsed_s=6 est_remaining_s=-3 / 3
tid=0 thread_prefill_amount=62490 percent_done_estimate=132.0 elapsed_s=6 est_remaining_s=-3 / 3
tid=0 thread_prefill_amount=64573 percent_done_estimate=136.4 elapsed_s=6 est_remaining_s=-4 / 2
tid=0 thread_prefill_amount=66656 percent_done_estimate=140.8 elapsed_s=6 est_remaining_s=-4 / 2
tid=0 thread_prefill_amount=68739 percent_done_estimate=145.3 elapsed_s=6 est_remaining_s=-4 / 2
tid=0 thread_prefill_amount=70822 percent_done_estimate=149.7 elapsed_s=6 est_remaining_s=-5 / 2
tid=0 thread_prefill_amount=72905 percent_done_estimate=154.1 elapsed_s=6 est_remaining_s=-5 / 1
tid=0 thread_prefill_amount=74988 percent_done_estimate=158.5 elapsed_s=7 est_remaining_s=-5 / 1
tid=0 thread_prefill_amount=77071 percent_done_estimate=162.9 elapsed_s=7 est_remaining_s=-6 / 1
tid=0 thread_prefill_amount=79154 percent_done_estimate=167.3 elapsed_s=7 est_remaining_s=-6 / 1
tid=0 thread_prefill_amount=81237 percent_done_estimate=171.7 elapsed_s=7 est_remaining_s=-7 / 0
tid=0 thread_prefill_amount=83320 percent_done_estimate=176.1 elapsed_s=7 est_remaining_s=-7 / 0
tid=0 thread_prefill_amount=85403 percent_done_estimate=180.5 elapsed_s=7 est_remaining_s=-7 / -0
tid=0 thread_prefill_amount=87486 percent_done_estimate=184.9 elapsed_s=7 est_remaining_s=-8 / -0
tid=0 thread_prefill_amount=89569 percent_done_estimate=189.3 elapsed_s=8 est_remaining_s=-8 / -0
tid=0 thread_prefill_amount=91652 percent_done_estimate=193.7 elapsed_s=8 est_remaining_s=-8 / -1
tid=0 thread_prefill_amount=93735 percent_done_estimate=198.1 elapsed_s=8 est_remaining_s=-9 / -1
tid=0 thread_prefill_amount=95818 percent_done_estimate=202.5 elapsed_s=8 est_remaining_s=-9 / -1
tid=0 thread_prefill_amount=97901 percent_done_estimate=206.9 elapsed_s=8 est_remaining_s=-10 / -1
tid=0 thread_prefill_amount=99984 percent_done_estimate=211.3 elapsed_s=8 est_remaining_s=-10 / -2
timing_elapsed 3.16656s
prefilling_size=10000000 prefilling_elapsed_millis=3.174s
finished prefilling to size 10000000 for expected size 10000000 keysum=100028042149572, performing 23291770 updates; total_prefilling_elapsed_ms=3174 ms)
pref_size=10000000
pref_millis=3174
prefill_elapsed_ms=3174
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6993880.192165483
REALTIME_END_PERF_FORMAT=6993890.192246505
###############################################################################
################################## 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.028s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 261963 518312 961097 1436434 1398394 720195 162704 12240 128 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 159 5011 64932 398873 1148413 1526483 887065 206800 15279 147
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262122 523323 1026029 1835307 2546807 2246678 1049769 219040 15407 147
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262122 523323 1026029 1835307 2546807 2246678 1049769 219040 15407 147
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99984 1.99709 1.97018 1.85203 1.60501 1.33308 1.14669 1.05367 1.01446 1.00123 1
tree_stats_height=28
tree_stats_numInternals=5733609
tree_stats_numLeaves=4253163
tree_stats_numNodes=9986772
tree_stats_numKeys=9986772
tree_stats_avgDegreeInternal=1.7418
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42588
tree_stats_avgKeyDepth=21.6983
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=276479
first_thread_announced_epoch_by_thread=6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302 6302
sum_num_inserts_by_thread=132566 133436 132933 133360 133476 131710 131667 132371 133202 135052 133239 132406 131566 133049 132253 134813 133004 132905 132211 132753 134500 133724 132090 131964 131490 133230 133674 131147 133171 132288 132726 133281 134684 134435 133941 132492 132605 133783 133791 133983 132920 132850 132705 132759 133402 134076 130746 132107 132703 133051 133753 133461 133803 132765 132837 132746 134085 134103 133482 132980 132083 133497 134459 135249 133553 132204 132742 131901 134057 133880 132491 131355 130752 133474 133929 133409 133205 132187 132974 132823 134055 134256 133678 133483 132217 133385 134258 134036 132379 131948 132402 130026 134424 134375 133945 129671
sum_num_inserts_total=12771067
sum_num_deletes_by_thread=131990 133466 133671 133273 133444 132109 132587 131577 134082 135390 133369 132979 130904 133817 132332 133978 132941 132844 132744 132802 134081 134019 132132 132063 131256 133367 132914 131894 133741 131819 132324 133546 135087 135131 133307 132348 132992 132969 132878 134262 132580 132224 132350 132461 133677 133627 130305 131960 132918 132607 133440 133454 133861 132768 132406 132173 134330 133716 132425 133644 132798 133523 134921 134055 133853 132698 132276 131855 132985 133729 133114 132078 131498 133315 133855 133297 133102 132205 131900 132553 134028 134804 132393 132814 132530 133383 133866 133506 132300 132428 132866 130489 134477 134057 133517 130892
sum_num_deletes_total=12767315
sum_num_searches_by_thread=2377943 2401293 2404699 2398140 2399785 2376214 2377323 2380044 2404845 2432307 2395973 2395436 2358747 2401182 2378018 2415430 2396801 2386201 2384442 2392333 2412241 2418480 2376646 2377197 2367519 2398243 2395188 2374879 2403353 2379401 2386816 2399868 2421451 2425741 2403909 2393368 2392367 2407500 2402911 2415264 2394014 2384987 2377659 2388514 2407988 2411449 2348949 2370571 2380907 2392955 2400329 2402893 2399123 2389676 2386166 2391051 2409869 2415489 2392618 2395002 2389973 2401075 2422457 2416525 2407791 2386673 2386743 2377393 2398559 2413358 2385823 2368296 2358416 2395228 2405032 2394421 2394799 2383962 2383079 2397843 2416161 2419891 2391223 2396702 2381982 2397617 2422036 2408714 2387357 2380448 2382385 2342898 2416177 2420941 2406492 2349954
sum_num_searches_total=229838131
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2642499 2668195 2671303 2664773 2666705 2640033 2641577 2643992 2672129 2702749 2662581 2660821 2621217 2668048 2642603 2684221 2662746 2651950 2649397 2657888 2680822 2686223 2640868 2641224 2630265 2664840 2661776 2637920 2670265 2643508 2651866 2666695 2691222 2695307 2671157 2658208 2657964 2674252 2669580 2683509 2659514 2650061 2642714 2653734 2675067 2679152 2610000 2634638 2646528 2658613 2667522 2669808 2666787 2655209 2651409 2655970 2678284 2683308 2658525 2661626 2654854 2668095 2691837 2685829 2675197 2651575 2651761 2641149 2665601 2680967 2651428 2631729 2620666 2662017 2672816 2661127 2661106 2648354 2647953 2663219 2684244 2688951 2657294 2662999 2646729 2664385 2690160 2676256 2652036 2644824 2647653 2603413 2685078 2689373 2673954 2610517
average_num_operations_total=2660172
stdev_num_operations_total=1972
sum_num_operations_total=255376513
min_num_operations_total=2603413
max_num_operations_total=2702749
first_time_thread_terminate_by_thread=10000155 10000081 10000081 10000084 10000085 10000085 10000081 10000085 10000081 10000082 10000083 10000153 10000081 10000086 10000080 10000082 10000082 10000081 10000085 10000083 10000083 10000085 10000083 10000081 10000082 10000083 10000086 10000083 10000085 10000084 10000082 10000085 10000081 10000082 10000081 10000081 10000086 10000081 10000083 10000085 10000083 10000081 10000081 10000083 10000080 10000085 10000081 10000081 10000085 10000084 10000082 10000084 10000082 10000082 10000084 10000084 10000082 10000083 10000084 10000085 10000082 10000083 10000082 10000085 10000083 10000084 10000084 10000082 10000082 10000082 10000084 10000082 10000085 10000084 10000083 10000084 10000082 10000082 10000082 10000084 10000082 10000083 10000084 10000086 10000082 10000083 10000084 10000085 10000083 10000084 10000084 10000084 10000084 10000083 10000084 10000082
min_time_thread_terminate_total=10000080
max_time_thread_terminate_total=10000155
first_time_thread_start_by_thread=9 5 5 5 6 5 6 5 6 5 6 5 5 6 5 6 5 5 5 5 5 5 5 5 4 5 5 5 6 5 5 5 6 5 6 5 5 6 5 6 5 5 5 5 5 5 5 5 6 6 6 6 6 5 6 6 5 5 6 6 6 5 6 5 5 5 6 6 6 6 6 6 6 6 6 6 6 5 6 6 5 5 6 6 6 5 6 5 5 5 6 6 6 6 6 6
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=
threads_final_keysum=99871348665407
threads_final_size=9986772
final_keysum=99871348665407
final_size=9986772
validate_result=success
Validation OK.
Structural validation OK.
total_find=229838131
total_rq=0
total_inserts=12771067
total_deletes=12767315
total_updates=25538382
total_queries=229838131
total_ops=255376513
find_throughput=22983813
rq_throughput=0
update_throughput=2553838
query_throughput=22983813
total_throughput=25537651
total find : 229838131
total rq : 0
total inserts : 12771067
total deletes : 12767315
total updates : 25538382
total queries : 229838131
total ops : 255376513
find throughput : 22983813
rq throughput : 0
update throughput : 2553838
query throughput : 22983813
total throughput : 25537651
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=22.0339
PAPI_L3_TCM=7.58759
PAPI_TOT_CYC=10342.4
PAPI_TOT_INS=8414.88
garbage=1150616606725503
total_execution_walltime=18.643s
REALTIME_MAIN_END_PERF_FORMAT=6993890.345475306
HyTM1 system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 278387523
[slow htm]
[fallback]
total fallback commit : 280761
seconds global lock is held : 12.0467
[time_cmd_output] time_elapsed_sec=18.85, faults_major=0, faults_minor=494310, mem_maxresident_kb=1195776, user_cputime=1752.60, sys_cputime=13.06, percent_cpu=9365%
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=1