__step=000201
__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.tl2 -nwork 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 200000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000201.txt
__path_data=data/data000201.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=222306.509783689
binary=./brown_int_bst_tm_auto.debra.tl2
parsed custom binding: 0-63,128-191,64-127,192-255
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=200000
PREFILL_THREADS=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63
TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.021s
prefilling_size=99721 prefilling_elapsed_millis=1s
finished prefilling to size 99728 for expected size 100000 keysum=9991639065, performing 12832849 updates; total_prefilling_elapsed_ms=1101 ms)
pref_size=99728
pref_millis=1101
prefill_elapsed_ms=1110
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=334
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=222307.659040677
REALTIME_END_PERF_FORMAT=222317.659125263
###############################################################################
################################## TIME IS UP #################################
###############################################################################
joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################
10s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (256 threads)...
partitioned into 1194 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.088s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 63 125 225 399 658 1050 1587 2381 3428 4668 5960 6931 7378 7108 6299 5373 4184 2993 2032 1247 647 305 123 48 13 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 13 26 80 144 269 427 708 1184 1836 2675 3479 4081 4134 3846 3370 2822 2033 1431 953 514 240 103 39 13 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 63 126 238 425 738 1194 1856 2808 4136 5852 7796 9606 10857 11189 10433 9219 7554 5815 4065 2678 1600 819 363 151 52 14 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 63 126 238 425 738 1194 1856 2808 4136 5852 7796 9606 10857 11189 10433 9219 7554 5815 4065 2678 1600 819 363 151 52 14 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 1.96875 2 1.89683 1.84034 1.79765 1.72629 1.67504 1.65787 1.625 1.58607 1.53452 1.46768 1.4087 1.35102 1.29717 1.27988 1.23658 1.21591 1.18435 1.15892 1.13181 1.1075 1.07082 1.07713 1.02649 1.01923 1 1
tree_stats_height=33
tree_stats_numInternals=65289
tree_stats_numLeaves=34422
tree_stats_numNodes=99711
tree_stats_numKeys=99711
tree_stats_avgDegreeInternal=1.52723
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34522
tree_stats_avgKeyDepth=18.807
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=10802
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:6 2:89 4:8936 8:344528 16:285802 32:421 64:89
[2^00, 2^01]: 6
(2^01, 2^02]: 89
(2^02, 2^03]: 8936
(2^03, 2^04]: 344528
(2^04, 2^05]: 285802
(2^05, 2^06]: 421
(2^06, 2^07]: 89
sum_limbo_reclamation_event_size_total=9705296
count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998
count_limbo_reclamation_event_size_total=639871
sum_limbo_reclamation_event_count_total=669952
first_thread_announced_epoch_by_thread=21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604 21604
sum_num_inserts_by_thread=321337 321280 318823 317916 319247 317833 317924 315760 316540 320271 319517 318814 318823 320239 319573 318171 318956 316976 313301 313609 313292 312572 314400 313678 319505 317184 318010 317454 317532 318864 318120 315080 316342 314505 313891 312975 314637 313739 320061 318484 318601 318897 318752 318730 317248 312865 315347 313961 314225 313701 313929 312969 317705 318195 317534 319331 318132 318490 317675 316909 323000 329186 324492 323606
sum_num_inserts_total=20318715
sum_num_deletes_by_thread=322011 321608 318601 317383 318743 316677 318570 315734 316200 320215 319650 318597 319739 319431 319188 318971 317943 317843 314183 313950 314993 313987 314879 312304 318792 319690 317367 318218 318014 318360 318875 315067 314567 314059 314332 312848 314148 313867 319792 318055 319425 317238 318749 319747 318543 312723 314877 313815 313551 313639 313687 314588 318663 319455 318245 318316 317302 318840 317749 317911 321356 329074 324170 324093
sum_num_deletes_total=20323207
sum_num_searches_by_thread=5786797 5789642 5735427 5715396 5727808 5709409 5714503 5692505 5696273 5757545 5737356 5736647 5744861 5746359 5742027 5744163 5719932 5697483 5661197 5662441 5663167 5647995 5657984 5636781 5739912 5730778 5724839 5725209 5721336 5724287 5725941 5675310 5667122 5655495 5658511 5645041 5669913 5663721 5742833 5734725 5744162 5736464 5739318 5735509 5716346 5621684 5674508 5654215 5646595 5640224 5640178 5642436 5740600 5738217 5723097 5728729 5721845 5737548 5717672 5703083 5813615 5927217 5837083 5843488
sum_num_searches_total=365752504
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=6430145 6432530 6372851 6350695 6365798 6343919 6350997 6323999 6329013 6398031 6376523 6374058 6383423 6386029 6380788 6381305 6356831 6332302 6288681 6290000 6291452 6274554 6287263 6262763 6378209 6367652 6360216 6360881 6356882 6361511 6362936 6305457 6298031 6284059 6286734 6270864 6298698 6291327 6382686 6371264 6382188 6372599 6376819 6373986 6352137 6247272 6304732 6281991 6274371 6267564 6267794 6269993 6376968 6375867 6358876 6366376 6357279 6374878 6353096 6337903 6457971 6585477 6485745 6491187
average_num_operations_total=6349912
stdev_num_operations_total=7675
sum_num_operations_total=406394426
min_num_operations_total=6247272
max_num_operations_total=6585477
first_time_thread_terminate_by_thread=10000087 10000084 10000084 10000084 10000083 10000083 10000083 10000087 10000085 10000085 10000085 10000085 10000086 10000086 10000086 10000086 10000086 10000086 10000086 10000086 10000086 10000082 10000082 10000082 10000083 10000083 10000083 10000083 10000085 10000085 10000085 10000085 10000085 10000085 10000086 10000085 10000085 10000085 10000085 10000085 10000084 10000084 10000084 10000084 10000085 10000085 10000086 10000085 10000083 10000089 10000083 10000083 10000084 10000090 10000085 10000085 10000085 10000088 10000085 10000085 10000084 10000084 10000084 10000084
min_time_thread_terminate_total=10000082
max_time_thread_terminate_total=10000090
first_time_thread_start_by_thread=7 7 7 7 5 5 5 5 5 5 5 5 5 5 5 5 8 8 8 8 6 6 6 6 8 8 8 8 8 8 8 8 6 6 6 6 6 6 6 6 7 7 7 7 5 5 5 5 8 9 8 8 7 7 7 7 7 7 7 7 7 6 6 6
min_time_thread_start_total=5
max_time_thread_start_total=9
sum_duration_all_ops_total=
threads_final_keysum=9963543831
threads_final_size=99711
final_keysum=9963543831
final_size=99711
validate_result=success
Validation OK.
Structural validation OK.
total_find=365752504
total_rq=0
total_inserts=20318715
total_deletes=20323207
total_updates=40641922
total_queries=365752504
total_ops=406394426
find_throughput=36575250
rq_throughput=0
update_throughput=4064192
query_throughput=36575250
total_throughput=40639442
total find : 365752504
total rq : 0
total inserts : 20318715
total deletes : 20323207
total updates : 40641922
total queries : 365752504
total ops : 406394426
find throughput : 36575250
rq throughput : 0
update throughput : 4064192
query throughput : 36575250
total throughput : 40639442
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=5413917176491
total_execution_walltime=11.32s
REALTIME_MAIN_END_PERF_FORMAT=222317.830628836
TL2 system shutdown:
GCLOCK=0x1449418 Starts=426307924 Aborts=7080648
Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=11.42, faults_major=0, faults_minor=78504, mem_maxresident_kb=308396, user_cputime=701.50, sys_cputime=35.31, percent_cpu=6450%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.tl2
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1