__step=000730
__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=data000730.txt
__path_data=data/data000730.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6987717.308840854
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.016s
prefilling_size=6555985 prefilling_elapsed_millis=1.001s
prefilling_size=6830159 prefilling_elapsed_millis=1.101s
prefilling_size=7079061 prefilling_elapsed_millis=1.201s
prefilling_size=7303634 prefilling_elapsed_millis=1.301s
prefilling_size=7508164 prefilling_elapsed_millis=1.401s
prefilling_size=7690631 prefilling_elapsed_millis=1.502s
prefilling_size=7859880 prefilling_elapsed_millis=1.602s
prefilling_size=8012537 prefilling_elapsed_millis=1.702s
prefilling_size=8153464 prefilling_elapsed_millis=1.802s
prefilling_size=8282713 prefilling_elapsed_millis=1.902s
prefilling_size=8402725 prefilling_elapsed_millis=2.003s
prefilling_size=8511717 prefilling_elapsed_millis=2.103s
prefilling_size=8612641 prefilling_elapsed_millis=2.203s
prefilling_size=8706295 prefilling_elapsed_millis=2.303s
prefilling_size=8791017 prefilling_elapsed_millis=2.403s
prefilling_size=8869677 prefilling_elapsed_millis=2.504s
prefilling_size=8943001 prefilling_elapsed_millis=2.604s
prefilling_size=9009870 prefilling_elapsed_millis=2.704s
prefilling_size=9073608 prefilling_elapsed_millis=2.804s
prefilling_size=9129869 prefilling_elapsed_millis=2.904s
prefilling_size=9183085 prefilling_elapsed_millis=3.005s
prefilling_size=9234793 prefilling_elapsed_millis=3.105s
prefilling_size=9282612 prefilling_elapsed_millis=3.205s
prefilling_size=9326829 prefilling_elapsed_millis=3.305s
prefilling_size=9368835 prefilling_elapsed_millis=3.405s
prefilling_size=9407333 prefilling_elapsed_millis=3.506s
prefilling_size=9443705 prefilling_elapsed_millis=3.606s
prefilling_size=9478014 prefilling_elapsed_millis=3.706s
prefilling_size=9509675 prefilling_elapsed_millis=3.806s
prefilling_size=9540557 prefilling_elapsed_millis=3.906s
prefilling_size=9567367 prefilling_elapsed_millis=4.006s
prefilling_size=9594066 prefilling_elapsed_millis=4.107s
prefilling_size=9619070 prefilling_elapsed_millis=4.207s
prefilling_size=9640234 prefilling_elapsed_millis=4.307s
prefilling_size=9662120 prefilling_elapsed_millis=4.407s
prefilling_size=9681604 prefilling_elapsed_millis=4.507s
prefilling_size=9700880 prefilling_elapsed_millis=4.608s
prefilling_size=9718088 prefilling_elapsed_millis=4.708s
prefilling_size=9734420 prefilling_elapsed_millis=4.808s
prefilling_size=9750460 prefilling_elapsed_millis=4.908s
prefilling_size=9765939 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 234048 keys with 48 threads...
tid=0 thread_prefill_amount=154142 percent_done_estimate=3161.2 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0354507s
prefilling_size=10000000 prefilling_elapsed_millis=0.035s
finished prefilling to size 10000000 for expected size 10000000 keysum=100007918982428, performing 98599565 updates; total_prefilling_elapsed_ms=35 ms)
pref_size=10000000
pref_millis=35
prefill_elapsed_ms=35
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=4493

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6987722.624162766
REALTIME_END_PERF_FORMAT=6987732.624242689

###############################################################################
################################## 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 (48 threads)...
partitioned into 256 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 256 509 1006 1976 3772 6990 12484 21318 34920 54764 82691 119848 166053 221131 282348 344274 403583 454732 492251 512142 511766 492132 456528 408081 351182 291270 232438 178778 132777 94992 65569 43560 27764 17220 10031 5710 3121 1754 911 438 205 90 41 17 3 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 4 21 107 315 853 2045 4287 8280 14562 24785 39814 59941 85987 118246 152269 188030 221989 250554 271455 281359 277711 265503 244049 215180 183175 149345 117258 88798 64619 45446 30772 19649 12665 7305 4311 2291 1320 690 343 168 70 30 16 1 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 511 1010 1997 3879 7305 13337 23363 39207 63044 97253 144633 205867 281072 368335 462520 555852 642762 714240 762696 783221 773491 734239 673584 595231 506450 415613 328123 250035 183790 130188 89006 58536 36869 22696 13015 7432 4045 2231 1128 548 258 111 47 19 3 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 511 1010 1997 3879 7305 13337 23363 39207 63044 97253 144633 205867 281072 368335 462520 555852 642762 714240 762696 783221 773491 734239 673584 595231 506450 415613 328123 250035 183790 130188 89006 58536 36869 22696 13015 7432 4045 2231 1128 548 258 111 47 19 3 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99609 1.98043 1.98119 1.95293 1.9108 1.86886 1.8157 1.7657 1.71732 1.67396 1.63692 1.59474 1.55871 1.52372 1.48915 1.45745 1.43029 1.40374 1.37865 1.35542 1.33416 1.31301 1.29562 1.27784 1.26085 1.24552 1.23023 1.21717 1.20402 1.1915 1.18002 1.16826 1.15555 1.14853 1.13148 1.13231 1.12433 1.11792 1.09727 1.09752 1.09672 1.0814 1.05405 1.04255 1 1 1

tree_stats_height=55
tree_stats_numInternals=6543683
tree_stats_numLeaves=3455622
tree_stats_numNodes=9999305
tree_stats_numKeys=9999305

tree_stats_avgDegreeInternal=1.52809
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34559
tree_stats_avgKeyDepth=28.3214

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 49152 98112 193920 383424 744768 1402560 2560704 4485696 7527744 12104448 18672576 27769536 39526464 53965824 70720320 88803840 106723584 123410304 137134080 146437632 150378432 148510272 140973888 129328128 114284352 97238400 79797696 62999616 48006720 35287680 24996096 17089152 11238912 7078848 4357632 2498880 1426944 776640 428352 216576 105216 49536 21312 9024 3648 576 384
tree_stats_sizeInBytes=1919866560

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:3 16:44 32:11 64:363830 128:32593 256:47
    (2^03, 2^04]: 3
    (2^04, 2^05]: 44
    (2^05, 2^06]: 11
    (2^06, 2^07]: 363830
    (2^07, 2^08]: 32593
    (2^08, 2^09]: 47
sum_limbo_reclamation_event_size_total=45347852
count_limbo_reclamation_event_size_by_thread=8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261 8261
count_limbo_reclamation_event_size_total=396528
sum_limbo_reclamation_event_count_total=396653
first_thread_announced_epoch_by_thread=25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512 25512
sum_num_inserts_by_thread=1872387 1884698 1901966 1901518 1890936 1867876 1879118 1886489 1911806 1911906 1886193 1876323 1882968 1892900 1910368 1910812 1896606 1881466 1868540 1882120 1897757 1906886 1881583 1867618 1874335 1883350 1895404 1902110 1894939 1869192 1880900 1890919 1915074 1916424 1886782 1876984 1884497 1894999 1911249 1910343 1898614 1880714 1870036 1880362 1898862 1906376 1880645 1866690
sum_num_inserts_total=90720640
sum_num_deletes_by_thread=1873235 1884913 1900727 1904182 1892660 1871653 1879490 1890384 1914248 1914768 1889015 1875445 1883381 1895305 1908700 1911480 1895984 1882035 1871182 1882400 1899652 1906136 1882242 1862843 1873873 1886209 1897270 1900217 1892526 1870465 1880020 1890667 1913570 1913611 1889231 1875063 1883807 1895748 1907770 1907198 1898652 1885582 1869692 1879468 1895185 1905769 1880681 1864156
sum_num_deletes_total=90728490
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3745622 3769611 3802693 3805700 3783596 3739529 3758608 3776873 3826054 3826674 3775208 3751768 3766349 3788205 3819068 3822292 3792590 3763501 3739722 3764520 3797409 3813022 3763825 3730461 3748208 3769559 3792674 3802327 3787465 3739657 3760920 3781586 3828644 3830035 3776013 3752047 3768304 3790747 3819019 3817541 3797266 3766296 3739728 3759830 3794047 3812145 3761326 3730846
average_num_operations_total=3780190
stdev_num_operations_total=4194
sum_num_operations_total=181449130
min_num_operations_total=3730461
max_num_operations_total=3830035
first_time_thread_terminate_by_thread=10000141 10000080 10000080 10000079 10000079 10000079 10000079 10000080 10000080 10000079 10000079 10000080 10000079 10000079 10000079 10000079 10000079 10000079 10000081 10000079 10000079 10000080 10000080 10000079 10000080 10000080 10000079 10000079 10000079 10000080 10000081 10000079 10000080 10000081 10000079 10000080 10000080 10000079 10000079 10000080 10000079 10000080 10000079 10000079 10000079 10000080 10000079 10000079
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10000141
first_time_thread_start_by_thread=9 5 5 5 5 4 5 5 4 5 5 5 4 5 5 4 5 5 4 4 5 5 4 5 4 5 5 5 5 4 5 5 4 5 5 5 4 5 5 4 5 5 4 4 5 5 4 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99975614081540
threads_final_size=9999305
final_keysum=99975614081540
final_size=9999305
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=90720640
total_deletes=90728490
total_updates=181449130
total_queries=0
total_ops=181449130
find_throughput=0
rq_throughput=0
update_throughput=18144913
query_throughput=0
total_throughput=18144913


total find                    : 0
total rq                      : 0
total inserts                 : 90720640
total deletes                 : 90728490
total updates                 : 181449130
total queries                 : 0
total ops                     : 181449130
find throughput               : 0
rq throughput                 : 0
update throughput             : 18144913
query throughput              : 0
total throughput              : 18144913

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=37.9208
PAPI_L3_TCM=11.2372
PAPI_TOT_CYC=7356.77
PAPI_TOT_INS=559.585
garbage=0
total_execution_walltime=15.581s
REALTIME_MAIN_END_PERF_FORMAT=6987732.890534568
[time_cmd_output] time_elapsed_sec=15.91, faults_major=0, faults_minor=807199, mem_maxresident_kb=3230016, user_cputime=736.65, sys_cputime=7.12, percent_cpu=4673%
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=2