__step=000948
__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 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000948.txt
__path_data=data/data000948.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6990366.262947406
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=50
DEL_FRAC=50
RQ=0
RQSIZE=0
MAXKEY=200000
PREFILL_THREADS=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
RQ_THREADS=0
distribution=0
INS_DEL_FRAC=50.0 50.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,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


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.01s
prefilling_size=99802 prefilling_elapsed_millis=1.001s
finished prefilling to size 99975 for expected size 100000 keysum=9994992799, performing 51369503 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=99975
pref_millis=1102
prefill_elapsed_ms=1125
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=1108

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6990367.568231367
REALTIME_END_PERF_FORMAT=6990377.568379618

###############################################################################
################################## 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 (192 threads)...
partitioned into 1010 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.034s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 505 934 1575 2425 3427 4572 5732 6991 8032 8731 8953 8942 8395 7440 6308 4849 3653 2648 1896 1390 971 616 359 168 84 42 20 9 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 5 76 293 725 1423 2282 3412 4473 5950 7333 8509 8964 9489 9350 8572 7767 6045 4658 3400 2402 1809 1326 873 550 252 126 64 31 16 4
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 510 1010 1868 3150 4850 6854 9144 11464 13982 16064 17462 17906 17884 16790 14880 12616 9698 7306 5296 3792 2780 1942 1232 718 336 168 84 40 18 4
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 1 5 76 293 725 1423 2282 3412 4473 5950 7333 8509 8964 9489 9350 8572 7767 6045 4658 3400 2402 1809 1326 873 550 252 126 64 31 16 4
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99609 1.9902 1.92475 1.84315 1.76984 1.7066 1.66706 1.62686 1.60982 1.57445 1.54351 1.51271 1.49939 1.46941 1.44312 1.42392 1.38435 1.37668 1.36244 1.35801 1.36656 1.34928 1.3172 1.2914 1.23398 1.25 1.25 1.2381 1.225 1.11111 1

tree_stats_height=39
tree_stats_numInternals=100179
tree_stats_numLeaves=100180
tree_stats_numNodes=200359
tree_stats_numKeys=100180

tree_stats_avgDegreeInternal=2.00001
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=21.2962

tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16320 32320 59776 100800 155200 219328 292608 366848 447424 514048 558784 572992 572288 537280 476160 403712 310336 233792 169472 121344 88960 62144 39424 22976 10752 5376 2688 1280 576 128
tree_stats_sizeInBytes=6411488

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=12082


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:48178 4:2968 8:5948 16:11473 32:23096 64:49337 128:110967 256:536003 512:10401 1024:159
    (2^01, 2^02]: 48178
    (2^02, 2^03]: 2968
    (2^03, 2^04]: 5948
    (2^04, 2^05]: 11473
    (2^05, 2^06]: 23096
    (2^06, 2^07]: 49337
    (2^07, 2^08]: 110967
    (2^08, 2^09]: 536003
    (2^09, 2^10]: 10401
    (2^10, 2^11]: 159
sum_limbo_reclamation_event_size_total=250200088
count_limbo_reclamation_event_size_by_thread=8320 8251 8189 8277 8265 8298 8306 8286 8249 8336 8325 8326 8415 8284 8345 8218 8221 8259 8353 8257 8357 8240 8302 8347 8299 8420 8307 8288 8308 8350 8297 8284 8285 8274 8361 8285 8340 8348 8249 8354 8323 8258 8249 8230 8429 8371 8257 8327 8453 8361 8319 8330 8315 8248 8340 8341 8293 8268 8264 8286 8443 8363 8252 8318 8249 8396 8406 8396 8214 8291 8335 8329 8308 8389 8384 8397 8385 8321 8352 8398 8255 8303 8303 8365 8382 8400 8389 8335 8271 8357 8273 8454 8290 8164 8358 8368
count_limbo_reclamation_event_size_total=798530
sum_limbo_reclamation_event_count_total=940103
first_thread_announced_epoch_by_thread=24164 24164 24164 24164 24164 24158 24164 24164 24164 24158 24164 24164 24164 24158 24164 24164 24164 24164 24164 24164 24158 24164 24160 24160 24164 24164 24164 24160 24164 24164 24164 24160 24164 24164 24164 24164 24164 24164 24164 24160 24164 24162 24164 24164 24158 24160 24160 24160 24164 24158 24164 24162 24164 24164 24164 24164 24160 24164 24164 24160 24164 24164 24164 24158 24164 24160 24158 24164 24158 24164 24164 24164 24164 24164 24164 24158 24164 24158 24164 24160 24164 24164 24164 24164 24164 24164 24164 24164 24158 24160 24164 24164 24158 24164 24164 24158
sum_num_inserts_by_thread=2574656 2570254 2602065 2610318 2575743 2561064 2598995 2596778 2596626 2609867 2586614 2593155 2624352 2602526 2615659 2599544 2583916 2588277 2574022 2573537 2627252 2596962 2581722 2583199 2580002 2606309 2603458 2606239 2573162 2585289 2563803 2580051 2604545 2603522 2593100 2587294 2603264 2608731 2585070 2622176 2582258 2586066 2568841 2571406 2622979 2616963 2579629 2576236 2634292 2640933 2624488 2607689 2585722 2603917 2614795 2621150 2621089 2616098 2600112 2580228 2635492 2645540 2623399 2634138 2580333 2625253 2610802 2633678 2617628 2617845 2624569 2600661 2580562 2640507 2638308 2648735 2621294 2613086 2612618 2652930 2608281 2629096 2609680 2620855 2619934 2651913 2657015 2647759 2605737 2616330 2600110 2651439 2631031 2586008 2647307 2621173
sum_num_inserts_total=250249055
sum_num_deletes_by_thread=2574550 2570265 2601296 2611340 2579516 2562241 2597633 2597319 2600240 2610035 2586042 2592014 2625256 2603254 2613899 2604204 2584649 2588730 2572624 2577761 2626916 2597747 2580473 2590073 2582234 2605868 2608305 2605423 2577646 2583710 2562156 2581073 2604178 2599571 2592927 2586897 2602364 2611516 2589178 2618341 2583452 2584526 2568777 2570559 2625987 2619366 2582483 2575678 2634774 2641595 2623469 2607041 2583553 2603094 2610748 2624596 2622200 2611924 2599481 2580111 2642645 2644945 2624205 2632450 2578912 2625224 2610358 2629075 2614527 2616526 2623406 2603638 2584332 2638247 2633064 2650073 2621241 2615197 2612243 2653506 2607281 2629026 2609101 2616031 2626035 2651189 2654106 2648073 2605913 2619758 2598951 2651496 2632260 2586067 2646239 2619488
sum_num_deletes_total=250267706
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5149206 5140519 5203361 5221658 5155259 5123305 5196628 5194097 5196866 5219902 5172656 5185169 5249608 5205780 5229558 5203748 5168565 5177007 5146646 5151298 5254168 5194709 5162195 5173272 5162236 5212177 5211763 5211662 5150808 5168999 5125959 5161124 5208723 5203093 5186027 5174191 5205628 5220247 5174248 5240517 5165710 5170592 5137618 5141965 5248966 5236329 5162112 5151914 5269066 5282528 5247957 5214730 5169275 5207011 5225543 5245746 5243289 5228022 5199593 5160339 5278137 5290485 5247604 5266588 5159245 5250477 5221160 5262753 5232155 5234371 5247975 5204299 5164894 5278754 5271372 5298808 5242535 5228283 5224861 5306436 5215562 5258122 5218781 5236886 5245969 5303102 5311121 5295832 5211650 5236088 5199061 5302935 5263291 5172075 5293546 5240661
average_num_operations_total=5213716
stdev_num_operations_total=4773
sum_num_operations_total=500516761
min_num_operations_total=5123305
max_num_operations_total=5311121
first_time_thread_terminate_by_thread=10000146 10000147 10000147 10000147 10000146 10000180 10000146 10000447 10000146 10000161 10000148 10000148 10000147 10000271 10000146 10000280 10000146 10000279 10000148 10000146 10000196 10000146 10000338 10000176 10000147 10000147 10000147 10000214 10000148 10000146 10000146 10000376 10000146 10000148 10000359 10000148 10000146 10000146 10000245 10000171 10000146 10000243 10000147 10000146 10000253 10000430 10000207 10000301 10000220 10000288 10000147 10000208 10000147 10000266 10000148 10000146 10000343 10000148 10000313 10000197 10000147 10000148 10000148 10000172 10000146 10000444 10000186 10000146 10000213 10000148 10000179 10000147 10000147 10000266 10000148 10000165 10000394 10000363 10000148 10000278 10000147 10000148 10000148 10000148 10000147 10000148 10000148 10000261 10000365 10000193 10000402 10000146 10000328 10000148 10000346 10000278
min_time_thread_terminate_total=10000146
max_time_thread_terminate_total=10000447
first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=10022992507
threads_final_size=100180
final_keysum=10022992507
final_size=100180
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=250249055
total_deletes=250267706
total_updates=500516761
total_queries=0
total_ops=500516761
find_throughput=0
rq_throughput=0
update_throughput=50051676
query_throughput=0
total_throughput=50051676


total find                    : 0
total rq                      : 0
total inserts                 : 250249055
total deletes                 : 250267706
total updates                 : 500516761
total queries                 : 0
total ops                     : 500516761
find throughput               : 0
rq throughput                 : 0
update throughput             : 50051676
query throughput              : 0
total throughput              : 50051676

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=12.7904
PAPI_L3_TCM=3.64116
PAPI_TOT_CYC=4896.67
PAPI_TOT_INS=995.202
garbage=0
total_execution_walltime=11.555s
REALTIME_MAIN_END_PERF_FORMAT=6990377.819412150
[time_cmd_output] time_elapsed_sec=11.66, faults_major=0, faults_minor=80871, mem_maxresident_kb=321484, user_cputime=1006.73, sys_cputime=11.08, percent_cpu=8723%
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=2