__step=000378
__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" ./guerraoui_ext_bst_ticket.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=data000378.txt
__path_data=data/data000378.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983375.558706463
binary=./guerraoui_ext_bst_ticket.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=guerraoui_ext_bst_ticket
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=40
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.004s
prefilling_size=6711266 prefilling_elapsed_millis=1.001s
prefilling_size=7001750 prefilling_elapsed_millis=1.101s
prefilling_size=7261643 prefilling_elapsed_millis=1.201s
prefilling_size=7499118 prefilling_elapsed_millis=1.301s
prefilling_size=7714893 prefilling_elapsed_millis=1.402s
prefilling_size=7908615 prefilling_elapsed_millis=1.502s
prefilling_size=8087077 prefilling_elapsed_millis=1.602s
prefilling_size=8246072 prefilling_elapsed_millis=1.702s
prefilling_size=8394038 prefilling_elapsed_millis=1.803s
prefilling_size=8528827 prefilling_elapsed_millis=1.903s
prefilling_size=8651173 prefilling_elapsed_millis=2.003s
prefilling_size=8762239 prefilling_elapsed_millis=2.103s
prefilling_size=8864426 prefilling_elapsed_millis=2.203s
prefilling_size=8955931 prefilling_elapsed_millis=2.304s
prefilling_size=9042415 prefilling_elapsed_millis=2.404s
prefilling_size=9120677 prefilling_elapsed_millis=2.504s
prefilling_size=9191724 prefilling_elapsed_millis=2.604s
prefilling_size=9256815 prefilling_elapsed_millis=2.704s
prefilling_size=9318695 prefilling_elapsed_millis=2.805s
prefilling_size=9373477 prefilling_elapsed_millis=2.905s
prefilling_size=9423096 prefilling_elapsed_millis=3.005s
prefilling_size=9471100 prefilling_elapsed_millis=3.105s
prefilling_size=9513682 prefilling_elapsed_millis=3.205s
prefilling_size=9551242 prefilling_elapsed_millis=3.306s
prefilling_size=9588260 prefilling_elapsed_millis=3.406s
prefilling_size=9619462 prefilling_elapsed_millis=3.506s
prefilling_size=9651114 prefilling_elapsed_millis=3.606s
prefilling_size=9678210 prefilling_elapsed_millis=3.706s
prefilling_size=9703928 prefilling_elapsed_millis=3.807s
prefilling_size=9727383 prefilling_elapsed_millis=3.907s
prefilling_size=9750270 prefilling_elapsed_millis=4.007s
prefilling_size=9770744 prefilling_elapsed_millis=4.107s
prefilling_size=9790092 prefilling_elapsed_millis=4.207s
prefilling_size=9805500 prefilling_elapsed_millis=4.308s
finished prefilling to size 9805536 for expected size 10000000 keysum=98040428899137, performing 78786689 updates; total_prefilling_elapsed_ms=4409 ms)
pref_size=9805536
pref_millis=4409
prefill_elapsed_ms=4430
6node_tIxPvE_object_size=40
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=3606

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6983380.134166371
REALTIME_END_PERF_FORMAT=6983390.134322250

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

tree_stats_computeWalltime=0.176s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 505 987 1905 3597 6679 12019 20895 35065 56637 87865 130614 185738 254965 335958 425827 517266 602805 674243 726274 752576 751044 721988 670932 601724 523842 441169 359396 285031 218753 163003 117791 82378 56488 37357 23773 14461 8520 4795 2547 1297 618 308 129 68 30 15 9 9 6 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 5 23 69 213 515 1339 3143 6725 13493 25409 45116 75490 116511 173972 246089 334388 431727 531367 622212 699972 754108 780100 773044 740140 679606 606515 522942 433761 351309 274503 208215 153204 108268 75619 50941 33085 20402 12245 7043 3797 1976 928 487 190 106 45 21 9 12 12
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 510 1010 1974 3810 7194 13358 24038 41790 70130 113274 175730 261228 371476 509930 671916 851654 1034532 1205610 1348486 1452548 1505152 1502088 1443976 1341864 1203448 1047684 882338 718792 570062 437506 326006 235582 164756 112976 74714 47546 28922 17040 9590 5094 2594 1236 616 258 136 60 30 18 18 12
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 1 5 23 69 213 515 1339 3143 6724 13493 25409 45116 75490 116511 173972 246089 334388 431727 531367 622212 699972 754108 780100 773044 740140 679606 606515 522942 433761 351309 274503 208215 153204 108268 75619 50941 33085 20402 12245 7043 3797 1976 928 487 190 106 45 21 9 12 12
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99609 1.9902 1.97723 1.96505 1.94409 1.92841 1.89976 1.86925 1.83905 1.8076 1.77569 1.74327 1.71102 1.68636 1.65883 1.63375 1.60737 1.58268 1.55925 1.53858 1.51811 1.49898 1.48066 1.46464 1.44842 1.43528 1.42109 1.40732 1.39654 1.38374 1.37257 1.36132 1.34968 1.34286 1.33066 1.31819 1.30415 1.29459 1.2814 1.26559 1.25461 1.23824 1.24919 1.20942 1.26357 1.22059 1.25 1.3 1.5 1.33333 1

tree_stats_height=59
tree_stats_numInternals=9920411
tree_stats_numLeaves=9920412
tree_stats_numNodes=19840823
tree_stats_numKeys=9920411

tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=30.7664

tree_stats_bytesAtDepth=40 80 160 320 640 1280 2560 5120 10240 20400 40400 78960 152400 287760 534320 961520 1671600 2805200 4530960 7029200 10449120 14859040 20397200 26876640 34066160 41381280 48224400 53939440 58101920 60206080 60083520 57759040 53674560 48137920 41907360 35293520 28751680 22802480 17500240 13040240 9423280 6590240 4519040 2988560 1901840 1156880 681600 383600 203760 103760 49440 24640 10320 5440 2400 1200 720 720 480
tree_stats_sizeInBytes=793632920

6node_tIxPvE_object_size=40
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=12189


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:56 4:1432 8:47555 16:317125 32:45718
    (2^01, 2^02]: 56
    (2^02, 2^03]: 1432
    (2^03, 2^04]: 47555
    (2^04, 2^05]: 317125
    (2^05, 2^06]: 45718
sum_limbo_reclamation_event_size_total=9358518
count_limbo_reclamation_event_size_by_thread=8581 8581 8581 8581 8580 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581 8580 8581 8581 8581 8581 8581 8581 8581 8581 8581 8581
count_limbo_reclamation_event_size_total=411886
sum_limbo_reclamation_event_count_total=411984
first_thread_announced_epoch_by_thread=24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378 24378
sum_num_inserts_by_thread=196635 196039 198841 198588 198061 195817 196588 198215 199066 199401 196994 196040 196532 197436 198752 199013 199210 196934 196153 196485 198095 199019 196690 195261 196198 196855 198185 198901 197088 195770 196536 197258 199245 198690 197566 195975 197110 198050 198912 199129 197262 196062 195707 197622 198025 198917 196219 195123
sum_num_inserts_total=9476270
sum_num_deletes_by_thread=196240 197590 199377 197744 198617 195537 196484 197890 199659 200060 197580 196526 197149 197636 199238 198636 198221 195958 195444 196521 198273 198618 196532 195180 196923 196939 198618 197932 197799 196058 196781 197586 198913 199177 196996 196973 197009 198543 199471 199534 197712 196799 195778 195906 197884 198936 196218 195147
sum_num_deletes_total=9480342
sum_num_searches_by_thread=3533689 3552826 3576196 3575738 3560540 3526513 3540421 3551572 3589293 3592535 3553625 3536441 3547720 3566548 3584077 3585374 3562050 3542590 3524044 3543403 3562678 3577523 3538803 3517126 3535192 3552694 3577737 3577199 3562687 3524795 3541783 3552222 3592318 3594371 3552499 3533600 3548498 3566268 3585147 3584470 3565265 3541704 3524128 3541596 3560975 3573750 3539122 3517915
sum_num_searches_total=170687260
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3926564 3946455 3974414 3972070 3957218 3917867 3933493 3947677 3988018 3991996 3948199 3929007 3941401 3961620 3982067 3983023 3959481 3935482 3915641 3936409 3959046 3975160 3932025 3907567 3928313 3946488 3974540 3974032 3957574 3916623 3935100 3947066 3990476 3992238 3947061 3926548 3942617 3962861 3983530 3983133 3960239 3934565 3915613 3935124 3956884 3971603 3931559 3908185
average_num_operations_total=3950914
stdev_num_operations_total=3514
sum_num_operations_total=189643872
min_num_operations_total=3907567
max_num_operations_total=3992238
first_time_thread_terminate_by_thread=10000153 10000154 10000154 10000154 10000154 10000155 10000154 10000154 10000154 10000154 10000154 10000153 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000155 10000154 10000154 10000153 10000154 10000154 10000154 10000154 10000155 10000154 10000154 10000154 10000154 10000154 10000153 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000155 10000154
min_time_thread_terminate_total=10000153
max_time_thread_terminate_total=10000155
first_time_thread_start_by_thread=4 5 5 5 4 5 5 5 4 5 5 4 5 4 4 4 4 5 5 5 5 5 5 4 4 5 5 5 4 5 5 5 4 5 5 4 5 4 4 4 4 5 5 5 5 5 5 4
min_time_thread_start_total=4
max_time_thread_start_total=5
sum_duration_all_ops_total=

threads_final_keysum=99201154960573
threads_final_size=9920411
final_keysum=99201154960573
final_size=9920411
validate_result=success
Validation OK.
Structural validation OK.

total_find=170687260
total_rq=0
total_inserts=9476270
total_deletes=9480342
total_updates=18956612
total_queries=170687260
total_ops=189643872
find_throughput=17068726
rq_throughput=0
update_throughput=1895661
query_throughput=17068726
total_throughput=18964387


total find                    : 170687260
total rq                      : 0
total inserts                 : 9476270
total deletes                 : 9480342
total updates                 : 18956612
total queries                 : 170687260
total ops                     : 189643872
find throughput               : 17068726
rq throughput                 : 0
update throughput             : 1895661
query throughput              : 17068726
total throughput              : 18964387

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=38.3515
PAPI_L3_TCM=13.8842
PAPI_TOT_CYC=7056.25
PAPI_TOT_INS=373.631
garbage=842507425068702
total_execution_walltime=14.854s
REALTIME_MAIN_END_PERF_FORMAT=6983390.413750544
[time_cmd_output] time_elapsed_sec=15.05, faults_major=0, faults_minor=309824, mem_maxresident_kb=1241432, user_cputime=723.70, sys_cputime=3.92, percent_cpu=4833%
timeout=false
algorithm=guerraoui_ext_bst_ticket.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2