__step=000714
__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 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000714.txt
__path_data=data/data000714.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6987503.572132983
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=50
DEL_FRAC=50
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=50.0 50.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.018s
prefilling_size=6714177 prefilling_elapsed_millis=1.001s
prefilling_size=7002489 prefilling_elapsed_millis=1.101s
prefilling_size=7264293 prefilling_elapsed_millis=1.201s
prefilling_size=7498987 prefilling_elapsed_millis=1.302s
prefilling_size=7713268 prefilling_elapsed_millis=1.402s
prefilling_size=7908318 prefilling_elapsed_millis=1.502s
prefilling_size=8085436 prefilling_elapsed_millis=1.602s
prefilling_size=8246932 prefilling_elapsed_millis=1.702s
prefilling_size=8394870 prefilling_elapsed_millis=1.803s
prefilling_size=8530360 prefilling_elapsed_millis=1.903s
prefilling_size=8653399 prefilling_elapsed_millis=2.003s
prefilling_size=8764898 prefilling_elapsed_millis=2.103s
prefilling_size=8865231 prefilling_elapsed_millis=2.203s
prefilling_size=8959712 prefilling_elapsed_millis=2.304s
prefilling_size=9045212 prefilling_elapsed_millis=2.404s
prefilling_size=9121041 prefilling_elapsed_millis=2.504s
prefilling_size=9192565 prefilling_elapsed_millis=2.604s
prefilling_size=9257852 prefilling_elapsed_millis=2.705s
prefilling_size=9318366 prefilling_elapsed_millis=2.805s
prefilling_size=9373263 prefilling_elapsed_millis=2.905s
prefilling_size=9423540 prefilling_elapsed_millis=3.005s
prefilling_size=9468456 prefilling_elapsed_millis=3.105s
prefilling_size=9510739 prefilling_elapsed_millis=3.206s
prefilling_size=9549817 prefilling_elapsed_millis=3.306s
prefilling_size=9586217 prefilling_elapsed_millis=3.406s
prefilling_size=9620394 prefilling_elapsed_millis=3.506s
prefilling_size=9651439 prefilling_elapsed_millis=3.606s
prefilling_size=9678851 prefilling_elapsed_millis=3.707s
prefilling_size=9704831 prefilling_elapsed_millis=3.807s
prefilling_size=9728531 prefilling_elapsed_millis=3.907s
prefilling_size=9750665 prefilling_elapsed_millis=4.007s
prefilling_size=9770955 prefilling_elapsed_millis=4.107s
prefilling_size=9789707 prefilling_elapsed_millis=4.208s
prefilling_size=9806035 prefilling_elapsed_millis=4.308s
finished prefilling to size 9806047 for expected size 10000000 keysum=98059797283844, performing 78784577 updates; total_prefilling_elapsed_ms=4409 ms)
pref_size=9806047
pref_millis=4409
prefill_elapsed_ms=4426
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=6987508.177781483
REALTIME_END_PERF_FORMAT=6987518.177913598

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

tree_stats_computeWalltime=0.217s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1019 1999 3867 7325 13463 24009 41296 67860 106433 158632 224920 305106 396032 491632 584014 664361 724866 761176 768656 747956 702267 637301 558263 475010 391067 311493 240824 180683 131254 93412 64906 43670 28185 17589 10531 6101 3453 1897 980 532 277 163 78 34 12 8 4 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 5 39 131 409 1187 2917 6722 14732 29287 54234 92344 144734 214180 300432 399250 503667 603856 688556 753696 789356 793645 767233 716339 641516 558953 470641 382162 300965 230112 169096 121918 86142 59155 38781 24647 14961 8749 5009 2814 1428 787 391 248 122 56 16 12 8
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2038 3998 7734 14650 26926 48018 82592 135720 212866 317264 449840 610212 792064 983264 1168028 1328722 1449732 1522352 1537312 1495912 1404534 1274602 1116526 950020 782134 622986 481648 361366 262508 186824 129812 87340 56370 35178 21062 12202 6906 3794 1960 1064 554 326 156 68 24 16 8
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 5 39 131 409 1187 2917 6722 14732 29286 54234 92344 144734 214180 300432 399250 503667 603856 688556 753696 789356 793645 767233 716339 641516 558953 470641 382162 300965 230112 169096 121918 86142 59155 38781 24647 14961 8749 5009 2814 1428 787 391 248 122 56 16 12 8
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 1.99512 1.98086 1.96723 1.94712 1.91898 1.89167 1.86001 1.82163 1.7842 1.74522 1.70894 1.67825 1.64901 1.6207 1.59395 1.56879 1.54554 1.52505 1.50491 1.48653 1.46946 1.45375 1.43799 1.42544 1.41164 1.39826 1.38656 1.37513 1.36322 1.35584 1.34742 1.33641 1.3227 1.31203 1.29936 1.28967 1.28299 1.27469 1.2583 1.27143 1.26034 1.29422 1.23926 1.21795 1.17647 1.33333 1.25 1

tree_stats_height=58
tree_stats_numInternals=9995639
tree_stats_numLeaves=9995640
tree_stats_numNodes=19991279
tree_stats_numKeys=9995639

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

tree_stats_bytesAtDepth=40 80 160 320 640 1280 2560 5120 10240 20480 40960 81520 159920 309360 586000 1077040 1920720 3303680 5428800 8514640 12690560 17993600 24408480 31682560 39330560 46721120 53148880 57989280 60894080 61492480 59836480 56181360 50984080 44661040 38000800 31285360 24919440 19265920 14454640 10500320 7472960 5192480 3493600 2254800 1407120 842480 488080 276240 151760 78400 42560 22160 13040 6240 2720 960 640 320
tree_stats_sizeInBytes=799651160

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:0 16:1 32:3 64:102 128:340094 256:24318 512:43 1024:94
    (2^04, 2^05]: 1
    (2^05, 2^06]: 3
    (2^06, 2^07]: 102
    (2^07, 2^08]: 340094
    (2^08, 2^09]: 24318
    (2^09, 2^10]: 43
    (2^10, 2^11]: 94
sum_limbo_reclamation_event_size_total=82447792
count_limbo_reclamation_event_size_by_thread=7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7596 7597 7597 7597
count_limbo_reclamation_event_size_total=364655
sum_limbo_reclamation_event_count_total=364752
first_thread_announced_epoch_by_thread=22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410
sum_num_inserts_by_thread=1712802 1719955 1731301 1732108 1724167 1712658 1715492 1718881 1735003 1736598 1719505 1710103 1717684 1726741 1733387 1736846 1724241 1714507 1708823 1718470 1724035 1730848 1711134 1703097 1714872 1720915 1730610 1731402 1724849 1709185 1711596 1723272 1736001 1736969 1718142 1709794 1720287 1726887 1737414 1736411 1723682 1713587 1705733 1716300 1721648 1728436 1715525 1705287
sum_num_inserts_total=82637190
sum_num_deletes_by_thread=1712774 1723339 1734566 1732476 1725920 1709049 1715156 1721882 1737141 1738220 1719700 1710455 1719814 1727720 1734985 1736955 1725019 1716064 1707919 1718153 1725541 1729812 1714439 1704010 1713555 1721749 1733556 1731790 1725879 1711027 1709610 1719960 1738303 1737514 1719566 1709603 1719869 1729530 1734646 1735875 1725534 1716128 1708424 1717717 1724901 1731784 1714183 1704503
sum_num_deletes_total=82676315
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3425576 3443294 3465867 3464584 3450087 3421707 3430648 3440763 3472144 3474818 3439205 3420558 3437498 3454461 3468372 3473801 3449260 3430571 3416742 3436623 3449576 3460660 3425573 3407107 3428427 3442664 3464166 3463192 3450728 3420212 3421206 3443232 3474304 3474483 3437708 3419397 3440156 3456417 3472060 3472286 3449216 3429715 3414157 3434017 3446549 3460220 3429708 3409790
average_num_operations_total=3444031
stdev_num_operations_total=2869
sum_num_operations_total=165313505
min_num_operations_total=3407107
max_num_operations_total=3474818
first_time_thread_terminate_by_thread=10000130 10000132 10000131 10000131 10000132 10000131 10000132 10000130 10000130 10000130 10000131 10000131 10000132 10000131 10000131 10000132 10000131 10000130 10000132 10000131 10000132 10000130 10000130 10000131 10000131 10000132 10000131 10000130 10000130 10000131 10000130 10000130 10000131 10000130 10000130 10000130 10000130 10000131 10000130 10000130 10000130 10000132 10000130 10000130 10000131 10000130 10000131 10000130
min_time_thread_terminate_total=10000130
max_time_thread_terminate_total=10000132
first_time_thread_start_by_thread=3 3 3 3 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 3 3 3 3 3 3 3 3 3 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 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=99954469880083
threads_final_size=9995639
final_keysum=99954469880083
final_size=9995639
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=82637190
total_deletes=82676315
total_updates=165313505
total_queries=0
total_ops=165313505
find_throughput=0
rq_throughput=0
update_throughput=16531350
query_throughput=0
total_throughput=16531350


total find                    : 0
total rq                      : 0
total inserts                 : 82637190
total deletes                 : 82676315
total updates                 : 165313505
total queries                 : 0
total ops                     : 165313505
find throughput               : 0
rq throughput                 : 0
update throughput             : 16531350
query throughput              : 0
total throughput              : 16531350

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=49.1482
PAPI_L3_TCM=18.2261
PAPI_TOT_CYC=8092.12
PAPI_TOT_INS=850.805
garbage=0
total_execution_walltime=14.932s
REALTIME_MAIN_END_PERF_FORMAT=6987518.507449914
[time_cmd_output] time_elapsed_sec=15.14, faults_major=0, faults_minor=313263, mem_maxresident_kb=1255364, user_cputime=724.66, sys_cputime=4.56, percent_cpu=4815%
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