__step=000074
__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 48 -nprefill 48 -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=data000074.txt
__path_data=data/data000074.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6993855.140940579
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=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
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
HyTM1 system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.006s
prefilling_size=2472610 prefilling_elapsed_millis=1.001s
prefilling_size=2675340 prefilling_elapsed_millis=1.101s
prefilling_size=2872316 prefilling_elapsed_millis=1.201s
prefilling_size=3065306 prefilling_elapsed_millis=1.301s
prefilling_size=3253420 prefilling_elapsed_millis=1.402s
prefilling_size=3438029 prefilling_elapsed_millis=1.502s
prefilling_size=3619692 prefilling_elapsed_millis=1.602s
prefilling_size=3841416 prefilling_elapsed_millis=1.702s
prefilling_size=4012900 prefilling_elapsed_millis=1.803s
prefilling_size=4179669 prefilling_elapsed_millis=1.903s
prefilling_size=4342034 prefilling_elapsed_millis=2.003s
prefilling_size=4501925 prefilling_elapsed_millis=2.103s
prefilling_size=4657592 prefilling_elapsed_millis=2.203s
prefilling_size=4808596 prefilling_elapsed_millis=2.304s
prefilling_size=4957000 prefilling_elapsed_millis=2.404s
prefilling_size=5101000 prefilling_elapsed_millis=2.504s
prefilling_size=5242015 prefilling_elapsed_millis=2.604s
prefilling_size=5379222 prefilling_elapsed_millis=2.704s
prefilling_size=5513587 prefilling_elapsed_millis=2.805s
prefilling_size=5644291 prefilling_elapsed_millis=2.905s
prefilling_size=5771590 prefilling_elapsed_millis=3.005s
prefilling_size=5895756 prefilling_elapsed_millis=3.105s
prefilling_size=6017919 prefilling_elapsed_millis=3.205s
prefilling_size=6135447 prefilling_elapsed_millis=3.306s
prefilling_size=6250734 prefilling_elapsed_millis=3.406s
prefilling_size=6361275 prefilling_elapsed_millis=3.506s
prefilling_size=6479291 prefilling_elapsed_millis=3.606s
prefilling_size=6604005 prefilling_elapsed_millis=3.706s
prefilling_size=6735514 prefilling_elapsed_millis=3.807s
prefilling_size=6839022 prefilling_elapsed_millis=3.907s
prefilling_size=6935371 prefilling_elapsed_millis=4.007s
prefilling_size=7029901 prefilling_elapsed_millis=4.107s
prefilling_size=7120867 prefilling_elapsed_millis=4.207s
prefilling_size=7209871 prefilling_elapsed_millis=4.308s
prefilling_size=7295876 prefilling_elapsed_millis=4.408s
prefilling_size=7379614 prefilling_elapsed_millis=4.508s
prefilling_size=7461187 prefilling_elapsed_millis=4.608s
prefilling_size=7540282 prefilling_elapsed_millis=4.708s
prefilling_size=7616996 prefilling_elapsed_millis=4.809s
prefilling_size=7691106 prefilling_elapsed_millis=4.909s
prefilling_size=7762684 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 2237233 keys with 48 threads...
tid=0 thread_prefill_amount=154142 percent_done_estimate=330.7 elapsed_s=5 est_remaining_s=-8 / -3
tid=0 thread_prefill_amount=158308 percent_done_estimate=339.7 elapsed_s=5 est_remaining_s=-8 / -3
tid=0 thread_prefill_amount=162474 percent_done_estimate=348.6 elapsed_s=5 est_remaining_s=-8 / -3
tid=0 thread_prefill_amount=166640 percent_done_estimate=357.5 elapsed_s=5 est_remaining_s=-9 / -3
tid=0 thread_prefill_amount=170806 percent_done_estimate=366.5 elapsed_s=5 est_remaining_s=-9 / -3
tid=0 thread_prefill_amount=174972 percent_done_estimate=375.4 elapsed_s=6 est_remaining_s=-9 / -4
tid=0 thread_prefill_amount=179138 percent_done_estimate=384.3 elapsed_s=6 est_remaining_s=-9 / -4
tid=0 thread_prefill_amount=183304 percent_done_estimate=393.3 elapsed_s=6 est_remaining_s=-10 / -4
tid=0 thread_prefill_amount=187470 percent_done_estimate=402.2 elapsed_s=6 est_remaining_s=-10 / -4
tid=0 thread_prefill_amount=191636 percent_done_estimate=411.2 elapsed_s=6 est_remaining_s=-10 / -4
tid=0 thread_prefill_amount=195802 percent_done_estimate=420.1 elapsed_s=6 est_remaining_s=-10 / -4
timing_elapsed 0.814263s
prefilling_size=10000000 prefilling_elapsed_millis=0.816s
finished prefilling to size 10000000 for expected size 10000000 keysum=100002125882024, performing 34320223 updates; total_prefilling_elapsed_ms=816 ms)
pref_size=10000000
pref_millis=816
prefill_elapsed_ms=816
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6993861.236164343
REALTIME_END_PERF_FORMAT=6993871.236237939
###############################################################################
################################## 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 (48 threads)...
partitioned into 256 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.048s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131070 262021 520172 974188 1464227 1397572 691998 147041 9545 37 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 111 3493 57494 399119 1191212 1541286 856126 186666 11934 44
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262132 523665 1031682 1863346 2588784 2233284 1003167 196211 11971 44
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262132 523665 1031682 1863346 2588784 2233284 1003167 196211 11971 44
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99992 1.99814 1.97679 1.86185 1.60351 1.32282 1.13933 1.04901 1.01236 1.00058 1
tree_stats_height=28
tree_stats_numInternals=5728942
tree_stats_numLeaves=4247487
tree_stats_numNodes=9976429
tree_stats_numKeys=9976429
tree_stats_avgDegreeInternal=1.74141
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42575
tree_stats_avgKeyDepth=21.675
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=419903
first_thread_announced_epoch_by_thread=20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724 20724
sum_num_inserts_by_thread=199747 199932 201891 201245 199712 198924 199297 199829 203202 201328 199369 198544 199687 200087 200742 201801 201552 200574 199006 200641 201307 201095 199914 198552 200322 200513 202166 201099 200703 199492 197933 199542 202504 197272 200018 198305 200336 199729 201455 200962 200123 200398 198553 199392 201754 201867 200079 198979
sum_num_inserts_total=9611474
sum_num_deletes_by_thread=199622 199942 201557 201010 201801 199555 197808 200017 201503 202421 198804 199049 200805 199735 200018 203165 200991 200313 198950 199287 200712 201486 200097 197197 199412 200222 201737 201250 201702 198288 199112 200284 203470 196872 200211 199739 200487 199062 202396 201715 201153 200749 197617 199465 201650 201451 199762 198431
sum_num_deletes_total=9612082
sum_num_searches_by_thread=3591491 3608419 3635134 3625191 3611888 3574818 3571901 3606246 3643469 3630019 3590724 3584269 3606513 3601508 3609251 3641511 3620945 3607486 3579665 3605316 3619265 3629998 3597839 3560442 3590479 3605761 3632370 3626766 3618736 3577812 3568063 3596934 3647635 3551577 3610900 3583464 3608695 3596741 3629043 3639651 3620090 3598946 3562019 3599136 3620161 3629039 3593085 3583136
sum_num_searches_total=173043547
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3990860 4008293 4038582 4027446 4013401 3973297 3969006 4006092 4048174 4033768 3988897 3981862 4007005 4001330 4010011 4046477 4023488 4008373 3977621 4005244 4021284 4032579 3997850 3956191 3990213 4006496 4036273 4029115 4021141 3975592 3965108 3996760 4053609 3945721 4011129 3981508 4009518 3995532 4032894 4042328 4021366 4000093 3958189 3997993 4023565 4032357 3992926 3980546
average_num_operations_total=4005564
stdev_num_operations_total=3787
sum_num_operations_total=192267103
min_num_operations_total=3945721
max_num_operations_total=4053609
first_time_thread_terminate_by_thread=10000126 10000073 10000073 10000072 10000074 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000072 10000073 10000073 10000073 10000072 10000073 10000073 10000074 10000074 10000073 10000073 10000074 10000074 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000072 10000073 10000073 10000073 10000072 10000073 10000073 10000073 10000072 10000073 10000073
min_time_thread_terminate_total=10000072
max_time_thread_terminate_total=10000126
first_time_thread_start_by_thread=8 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 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=99756859309485
threads_final_size=9976429
final_keysum=99756859309485
final_size=9976429
validate_result=success
Validation OK.
Structural validation OK.
total_find=173043547
total_rq=0
total_inserts=9611474
total_deletes=9612082
total_updates=19223556
total_queries=173043547
total_ops=192267103
find_throughput=17304354
rq_throughput=0
update_throughput=1922355
query_throughput=17304354
total_throughput=19226710
total find : 173043547
total rq : 0
total inserts : 9611474
total deletes : 9612082
total updates : 19223556
total queries : 173043547
total ops : 192267103
find throughput : 17304354
rq throughput : 0
update throughput : 1922355
query throughput : 17304354
total throughput : 19226710
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=21.5911
PAPI_L3_TCM=7.15182
PAPI_TOT_CYC=6904.16
PAPI_TOT_INS=2763.13
garbage=848920752985868
total_execution_walltime=16.294s
REALTIME_MAIN_END_PERF_FORMAT=6993871.436063110
HyTM1 system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 226311323
[slow htm]
[fallback]
total fallback commit : 276004
seconds global lock is held : 5.7107
[time_cmd_output] time_elapsed_sec=16.49, faults_major=0, faults_minor=501131, mem_maxresident_kb=1198976, user_cputime=770.78, sys_cputime=6.12, percent_cpu=4709%
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