__step=000729
__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" ./drachsler_pext_bst_lock.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=data000729.txt
__path_data=data/data000729.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6987702.36132678
binary=./drachsler_pext_bst_lock.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=drachsler_pext_bst_lock
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=192
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=6752385 prefilling_elapsed_millis=1.001s
prefilling_size=7040832 prefilling_elapsed_millis=1.101s
prefilling_size=7302012 prefilling_elapsed_millis=1.201s
prefilling_size=7537337 prefilling_elapsed_millis=1.301s
prefilling_size=7751752 prefilling_elapsed_millis=1.402s
prefilling_size=7944393 prefilling_elapsed_millis=1.502s
prefilling_size=8119410 prefilling_elapsed_millis=1.602s
prefilling_size=8277951 prefilling_elapsed_millis=1.702s
prefilling_size=8422646 prefilling_elapsed_millis=1.802s
prefilling_size=8553668 prefilling_elapsed_millis=1.903s
prefilling_size=8673524 prefilling_elapsed_millis=2.003s
prefilling_size=8783197 prefilling_elapsed_millis=2.103s
prefilling_size=8881328 prefilling_elapsed_millis=2.203s
prefilling_size=8973687 prefilling_elapsed_millis=2.304s
prefilling_size=9056738 prefilling_elapsed_millis=2.404s
prefilling_size=9133126 prefilling_elapsed_millis=2.504s
prefilling_size=9202800 prefilling_elapsed_millis=2.604s
prefilling_size=9267952 prefilling_elapsed_millis=2.704s
prefilling_size=9326057 prefilling_elapsed_millis=2.805s
prefilling_size=9379272 prefilling_elapsed_millis=2.905s
prefilling_size=9429471 prefilling_elapsed_millis=3.005s
prefilling_size=9476099 prefilling_elapsed_millis=3.105s
prefilling_size=9518889 prefilling_elapsed_millis=3.205s
prefilling_size=9556522 prefilling_elapsed_millis=3.306s
prefilling_size=9590816 prefilling_elapsed_millis=3.406s
prefilling_size=9621368 prefilling_elapsed_millis=3.506s
prefilling_size=9648930 prefilling_elapsed_millis=3.606s
prefilling_size=9675351 prefilling_elapsed_millis=3.706s
prefilling_size=9701067 prefilling_elapsed_millis=3.807s
prefilling_size=9723796 prefilling_elapsed_millis=3.907s
prefilling_size=9743250 prefilling_elapsed_millis=4.007s
prefilling_size=9762695 prefilling_elapsed_millis=4.107s
prefilling_size=9779625 prefilling_elapsed_millis=4.207s
prefilling_size=9795990 prefilling_elapsed_millis=4.308s
prefilling_size=9811730 prefilling_elapsed_millis=4.408s
finished prefilling to size 9811737 for expected size 10000000 keysum=98102755877891, performing 87001923 updates; total_prefilling_elapsed_ms=4509 ms)
pref_size=9811737
pref_millis=4509
prefill_elapsed_ms=4535
6node_tIxPvE_object_size=192
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=3966

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6987706.733558204
REALTIME_END_PERF_FORMAT=6987716.733703815

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

tree_stats_computeWalltime=0.104s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 505 995 1944 3711 6896 12403 21490 35748 56422 85194 122688 168787 222877 282028 343514 402729 453600 490096 507757 505705 484288 447262 397744 341850 284034 228460 178367 135178 98918 70444 49172 33477 22276 14702 9597 6206 3988 2432 1408 834 451 237 132 78 35 21 10 7 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 4 10 28 97 292 777 1840 3957 8233 15036 26132 41622 61962 88432 118327 152096 187599 222814 251349 270410 279186 275441 262413 239009 210097 178055 145691 115793 89649 66578 47946 33691 23253 15476 10184 6716 4381 2866 1771 1039 630 338 180 88 60 28 18 7 6 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 509 1005 1972 3808 7188 13180 23330 39705 64655 100230 148820 210409 284839 370460 461841 554825 641199 712910 759106 776115 763474 722703 660157 580859 494131 406515 324058 250971 188567 137022 97118 67168 45529 30178 19781 12922 8369 5298 3179 1873 1081 575 312 166 95 49 28 14 8 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 509 1005 1972 3808 7188 13180 23330 39705 64655 100230 148820 210409 284839 370460 461841 554825 641199 712910 759106 776115 763474 722703 660157 580859 494131 406515 324058 250971 188567 137022 97118 67168 45529 30178 19781 12922 8369 5298 3179 1873 1081 575 312 166 95 49 28 14 8 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.99608 1.98232 1.97214 1.94523 1.91308 1.87423 1.82906 1.78075 1.72804 1.67757 1.6348 1.58944 1.55155 1.51813 1.48538 1.45754 1.42981 1.40441 1.37734 1.35352 1.33213 1.31228 1.29458 1.27738 1.26217 1.24787 1.23516 1.22405 1.21273 1.20207 1.19467 1.1853 1.17943 1.17356 1.1683 1.16809 1.16739 1.15653 1.141 1.14627 1.13187 1.11471 1.13043 1.10897 1.10241 1.14737 1.14286 1.14286 1.07143 1 1

tree_stats_height=59
tree_stats_numInternals=6537209
tree_stats_numLeaves=3461609
tree_stats_numNodes=9998818
tree_stats_numKeys=9998818

tree_stats_avgDegreeInternal=1.52952
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.3462
tree_stats_avgKeyDepth=28.3751

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 48960 97728 192960 378624 731136 1380096 2530560 4479360 7623360 12413760 19244160 28573440 40398528 54689088 71128320 88673472 106526400 123110208 136878720 145748352 149014080 146587008 138758976 126750144 111524928 94873152 78050880 62219136 48186432 36204864 26308224 18646656 12896256 8741568 5794176 3797952 2481024 1606848 1017216 610368 359616 207552 110400 59904 31872 18240 9408 5376 2688 1536 384
tree_stats_sizeInBytes=1919773056

6node_tIxPvE_object_size=192
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=12355


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:0 16:1 32:12 64:373385 128:29178
    (2^04, 2^05]: 1
    (2^05, 2^06]: 12
    (2^06, 2^07]: 373385
    (2^07, 2^08]: 29178
sum_limbo_reclamation_event_size_total=45736757
count_limbo_reclamation_event_size_by_thread=8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387 8387
count_limbo_reclamation_event_size_total=402576
sum_limbo_reclamation_event_count_total=402672
first_thread_announced_epoch_by_thread=24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710 24710
sum_num_inserts_by_thread=1897956 1908333 1919885 1919798 1911326 1893659 1896699 1904756 1937510 1932716 1911174 1891792 1907477 1912034 1927390 1927840 1923019 1899467 1885982 1898563 1922464 1921949 1899800 1891039 1899929 1907045 1926977 1926500 1910985 1884813 1896159 1914875 1929233 1930541 1902677 1893965 1898656 1911283 1924822 1937257 1914306 1900739 1893652 1904633 1913266 1934350 1898774 1889711
sum_num_inserts_total=91687776
sum_num_deletes_by_thread=1896227 1909220 1919406 1917355 1910588 1893244 1899339 1905007 1938023 1930725 1913930 1893266 1908456 1912052 1926107 1928503 1922503 1899454 1884372 1898738 1925007 1923477 1900320 1888942 1899714 1908481 1928273 1928462 1908170 1884199 1896137 1913895 1929746 1932965 1903404 1891538 1900916 1913495 1925935 1934735 1912022 1897373 1893473 1904949 1910890 1930247 1900292 1889692
sum_num_deletes_total=91683264
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3794183 3817553 3839291 3837153 3821914 3786903 3796038 3809763 3875533 3863441 3825104 3785058 3815933 3824086 3853497 3856343 3845522 3798921 3770354 3797301 3847471 3845426 3800120 3779981 3799643 3815526 3855250 3854962 3819155 3769012 3792296 3828770 3858979 3863506 3806081 3785503 3799572 3824778 3850757 3871992 3826328 3798112 3787125 3809582 3824156 3864597 3799066 3779403
average_num_operations_total=3820230
stdev_num_operations_total=4271
sum_num_operations_total=183371040
min_num_operations_total=3769012
max_num_operations_total=3875533
first_time_thread_terminate_by_thread=10000144 10000144 10000144 10000144 10000144 10000144 10000145 10000144 10000144 10000145 10000145 10000146 10000144 10000145 10000144 10000144 10000145 10000144 10000145 10000144 10000144 10000144 10000145 10000144 10000144 10000144 10000144 10000144 10000144 10000144 10000145 10000144 10000145 10000144 10000144 10000146 10000145 10000144 10000144 10000144 10000144 10000144 10000145 10000145 10000144 10000144 10000144 10000144
min_time_thread_terminate_total=10000144
max_time_thread_terminate_total=10000146
first_time_thread_start_by_thread=5 5 5 5 5 5 6 5 6 5 5 5 5 6 5 5 6 6 6 6 5 6 6 5 5 5 5 5 5 5 6 5 6 5 5 5 5 6 5 5 6 6 6 6 5 6 6 5
min_time_thread_start_total=5
max_time_thread_start_total=6
sum_duration_all_ops_total=

threads_final_keysum=99978042987303
threads_final_size=9998818
final_keysum=99978042987303
final_size=9998818
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=91687776
total_deletes=91683264
total_updates=183371040
total_queries=0
total_ops=183371040
find_throughput=0
rq_throughput=0
update_throughput=18337104
query_throughput=0
total_throughput=18337104


total find                    : 0
total rq                      : 0
total inserts                 : 91687776
total deletes                 : 91683264
total updates                 : 183371040
total queries                 : 0
total ops                     : 183371040
find throughput               : 0
rq throughput                 : 0
update throughput             : 18337104
query throughput              : 0
total throughput              : 18337104

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=37.6402
PAPI_L3_TCM=11.2607
PAPI_TOT_CYC=7297.69
PAPI_TOT_INS=577.621
garbage=0
total_execution_walltime=14.904s
REALTIME_MAIN_END_PERF_FORMAT=6987716.941763221
[time_cmd_output] time_elapsed_sec=15.21, faults_major=0, faults_minor=560048, mem_maxresident_kb=2242300, user_cputime=724.02, sys_cputime=4.34, percent_cpu=4788%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1