__step=000385
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 60 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" ./natarajan_ext_bst_lf.debra -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=data000385.txt
__path_data=data/data000385.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983468.612599673
binary=./natarajan_ext_bst_lf.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=natarajan_ext_bst_lf
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=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
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.023s
prefilling_size=7218720 prefilling_elapsed_millis=1.001s
prefilling_size=7488875 prefilling_elapsed_millis=1.101s
prefilling_size=7728456 prefilling_elapsed_millis=1.201s
prefilling_size=7944093 prefilling_elapsed_millis=1.302s
prefilling_size=8136395 prefilling_elapsed_millis=1.402s
prefilling_size=8307952 prefilling_elapsed_millis=1.502s
prefilling_size=8463771 prefilling_elapsed_millis=1.602s
prefilling_size=8603403 prefilling_elapsed_millis=1.702s
prefilling_size=8730398 prefilling_elapsed_millis=1.803s
prefilling_size=8844665 prefilling_elapsed_millis=1.903s
prefilling_size=8947252 prefilling_elapsed_millis=2.003s
prefilling_size=9040679 prefilling_elapsed_millis=2.103s
prefilling_size=9127092 prefilling_elapsed_millis=2.203s
prefilling_size=9203884 prefilling_elapsed_millis=2.304s
prefilling_size=9274511 prefilling_elapsed_millis=2.404s
prefilling_size=9338278 prefilling_elapsed_millis=2.504s
prefilling_size=9394455 prefilling_elapsed_millis=2.604s
prefilling_size=9446720 prefilling_elapsed_millis=2.704s
prefilling_size=9493753 prefilling_elapsed_millis=2.805s
prefilling_size=9536563 prefilling_elapsed_millis=2.905s
prefilling_size=9576357 prefilling_elapsed_millis=3.005s
prefilling_size=9613140 prefilling_elapsed_millis=3.105s
prefilling_size=9645448 prefilling_elapsed_millis=3.206s
prefilling_size=9677662 prefilling_elapsed_millis=3.306s
prefilling_size=9704364 prefilling_elapsed_millis=3.406s
prefilling_size=9727746 prefilling_elapsed_millis=3.506s
prefilling_size=9751829 prefilling_elapsed_millis=3.606s
prefilling_size=9772910 prefilling_elapsed_millis=3.707s
prefilling_size=9792944 prefilling_elapsed_millis=3.807s
prefilling_size=9810272 prefilling_elapsed_millis=3.907s
finished prefilling to size 9810251 for expected size 10000000 keysum=98099455518151, performing 81517228 updates; total_prefilling_elapsed_ms=4008 ms)
pref_size=9810251
pref_millis=4008
prefill_elapsed_ms=4032
6node_tIxPvE_object_size=32
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0
global_epoch_counter=3737
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6983472.849935295
REALTIME_END_PERF_FORMAT=6983482.850090582
###############################################################################
################################## 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 (192 threads)...
partitioned into 1022 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.147s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 511 1015 2002 3861 7331 13544 24252 41703 68478 107855 162031 230674 313203 405684 504027 598547 681812 746050 782612 787667 761950 707581 630621 542486 450473 361641 282450 213487 157406 112896 79116 54068 36276 23231 14689 8804 5162 2945 1734 1063 622 295 127 54 22 8 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 1 7 28 143 391 1118 2836 6801 14928 29101 53679 93388 148145 220722 307341 409507 515282 617574 709488 777557 813384 816319 784541 718756 634499 539305 440832 351413 269568 201916 146676 104164 71860 49321 31773 20574 12446 7379 4156 2405 1504 949 463 200 86 36 14 4
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1022 2030 4004 7722 14662 27088 48504 83406 136956 215710 324062 461348 626406 811368 1008054 1197094 1363624 1492100 1565224 1575334 1523900 1415162 1261242 1084972 900946 723282 564900 426974 314812 225792 158232 108136 72552 46462 29378 17608 10324 5890 3468 2126 1244 590 254 108 44 16 4
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 1 7 28 143 391 1118 2836 6801 14928 29101 53679 93388 148145 220722 307341 409507 515282 617574 709488 777557 813384 816319 784541 718756 634499 539305 440832 351413 269568 201916 146676 104164 71860 49321 31773 20574 12446 7379 4156 2405 1504 949 463 200 86 36 14 4
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 1.99805 1.99315 1.98621 1.96429 1.94937 1.92375 1.8953 1.85978 1.82102 1.78752 1.75115 1.71182 1.67889 1.64764 1.62121 1.59376 1.56956 1.54711 1.5245 1.50323 1.48368 1.46432 1.44562 1.43012 1.41519 1.4014 1.39051 1.37792 1.36865 1.35861 1.35039 1.3417 1.33547 1.3202 1.31615 1.29968 1.29316 1.28526 1.2944 1.30652 1.29257 1.23714 1.21525 1.2126 1.2037 1.18182 1.125 1
tree_stats_height=57
tree_stats_numInternals=9932579
tree_stats_numLeaves=9932580
tree_stats_numNodes=19865159
tree_stats_numKeys=9932580
tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=29.9539
tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16384 32704 64960 128128 247104 469184 866816 1552128 2668992 4382592 6902720 10369984 14763136 20044992 25963776 32257728 38307008 43635968 47747200 50087168 50410688 48764800 45285184 40359744 34719104 28830272 23145024 18076800 13663168 10073984 7225344 5063424 3460352 2321664 1486784 940096 563456 330368 188480 110976 68032 39808 18880 8128 3456 1408 512 128
tree_stats_sizeInBytes=635685088
6node_tIxPvE_object_size=32
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0
global_epoch_counter=13276
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:61 4:1528 8:52230 16:353252 32:50640 64:28 128:35
(2^01, 2^02]: 61
(2^02, 2^03]: 1528
(2^03, 2^04]: 52230
(2^04, 2^05]: 353252
(2^05, 2^06]: 50640
(2^06, 2^07]: 28
(2^07, 2^08]: 35
sum_limbo_reclamation_event_size_total=10416820
count_limbo_reclamation_event_size_by_thread=9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9536 9537 9537 9537 9537 9537 9537 9537 9537 9536 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537 9537
count_limbo_reclamation_event_size_total=457774
sum_limbo_reclamation_event_count_total=457872
first_thread_announced_epoch_by_thread=26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552 26552
sum_num_inserts_by_thread=218316 218789 220073 221417 219896 217399 217842 220952 222748 222300 219542 218192 219245 219928 220793 221965 220291 219243 217460 218701 220532 221603 219467 217921 218799 218023 220581 221027 220375 216942 218608 219392 221914 221775 219681 218322 218340 220227 220804 219763 220285 219153 218176 219089 221363 221356 218556 218287
sum_num_inserts_total=10545453
sum_num_deletes_by_thread=217818 218961 220516 220912 220126 217044 218124 219704 220888 221829 219559 218291 218874 220633 221218 221377 220121 218290 216676 219823 220407 221192 219403 217419 218133 218450 220076 221295 219810 217506 217521 220011 221387 221651 219763 218761 219202 219618 221149 221033 219588 218833 217687 218980 220406 221019 219686 217588
sum_num_deletes_total=10538358
sum_num_searches_by_thread=3922144 3932862 3962111 3977217 3951788 3908801 3928374 3957767 3987943 3991693 3961142 3933717 3944497 3964549 3978661 3996631 3967381 3937007 3912933 3945055 3961777 3984204 3949884 3925666 3931456 3936080 3964757 3978518 3962079 3911821 3925840 3960645 3991647 3989596 3949141 3929778 3937889 3952447 3976644 3980422 3962935 3945425 3917575 3930980 3969521 3989700 3947805 3922371
sum_num_searches_total=189748876
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4358278 4370612 4402700 4419546 4391810 4343244 4364340 4398423 4431579 4435822 4400243 4370200 4382616 4405110 4420672 4439973 4407793 4374540 4347069 4383579 4402716 4426999 4388754 4361006 4368388 4372553 4405414 4420840 4402264 4346269 4361969 4400048 4434948 4433022 4388585 4366861 4375431 4392292 4418597 4421218 4402808 4383411 4353438 4369049 4411290 4432075 4386047 4358246
average_num_operations_total=4392347
stdev_num_operations_total=3951
sum_num_operations_total=210832687
min_num_operations_total=4343244
max_num_operations_total=4439973
first_time_thread_terminate_by_thread=10000154 10000154 10000154 10000154 10000156 10000154 10000154 10000155 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000155 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000155 10000154 10000154 10000154 10000154 10000155
min_time_thread_terminate_total=10000154
max_time_thread_terminate_total=10000156
first_time_thread_start_by_thread=3 4 4 3 3 3 4 3 3 4 4 4 3 3 3 3 3 3 3 3 4 3 3 4 3 4 4 3 3 3 4 3 3 4 4 4 3 3 3 3 3 3 3 3 4 3 3 4
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=
threads_final_keysum=99337546528376
threads_final_size=9932580
final_keysum=99337546528376
final_size=9932580
validate_result=success
Validation OK.
Structural validation OK.
total_find=189748876
total_rq=0
total_inserts=10545453
total_deletes=10538358
total_updates=21083811
total_queries=189748876
total_ops=210832687
find_throughput=18974887
rq_throughput=0
update_throughput=2108381
query_throughput=18974887
total_throughput=21083268
total find : 189748876
total rq : 0
total inserts : 10545453
total deletes : 10538358
total updates : 21083811
total queries : 189748876
total ops : 210832687
find throughput : 18974887
rq throughput : 0
update throughput : 2108381
query throughput : 18974887
total throughput : 21083268
elapsed milliseconds : 10000
napping milliseconds overtime : 0
PAPI_L2_TCM=25.3332
PAPI_L3_TCM=9.16949
PAPI_TOT_CYC=6347.98
PAPI_TOT_INS=413.628
garbage=938198055011689
total_execution_walltime=14.499s
REALTIME_MAIN_END_PERF_FORMAT=6983483.112130102
[time_cmd_output] time_elapsed_sec=14.67, faults_major=0, faults_minor=234586, mem_maxresident_kb=940484, user_cputime=704.77, sys_cputime=2.64, percent_cpu=4820%
timeout=false
algorithm=natarajan_ext_bst_lf.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1