__step=000393
__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 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=data000393.txt
__path_data=data/data000393.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983574.640328500
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=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=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.008s
prefilling_size=6835424 prefilling_elapsed_millis=1.001s
prefilling_size=7131718 prefilling_elapsed_millis=1.101s
prefilling_size=7398129 prefilling_elapsed_millis=1.201s
prefilling_size=7636209 prefilling_elapsed_millis=1.301s
prefilling_size=7853321 prefilling_elapsed_millis=1.401s
prefilling_size=8049173 prefilling_elapsed_millis=1.501s
prefilling_size=8226528 prefilling_elapsed_millis=1.602s
prefilling_size=8388860 prefilling_elapsed_millis=1.702s
prefilling_size=8534840 prefilling_elapsed_millis=1.802s
prefilling_size=8665685 prefilling_elapsed_millis=1.902s
prefilling_size=8784283 prefilling_elapsed_millis=2.003s
prefilling_size=8891990 prefilling_elapsed_millis=2.103s
prefilling_size=8991484 prefilling_elapsed_millis=2.203s
prefilling_size=9081289 prefilling_elapsed_millis=2.303s
prefilling_size=9162708 prefilling_elapsed_millis=2.403s
prefilling_size=9237712 prefilling_elapsed_millis=2.504s
prefilling_size=9305817 prefilling_elapsed_millis=2.604s
prefilling_size=9366043 prefilling_elapsed_millis=2.704s
prefilling_size=9423072 prefilling_elapsed_millis=2.804s
prefilling_size=9473687 prefilling_elapsed_millis=2.904s
prefilling_size=9520449 prefilling_elapsed_millis=3.005s
prefilling_size=9563580 prefilling_elapsed_millis=3.105s
prefilling_size=9601577 prefilling_elapsed_millis=3.205s
prefilling_size=9635609 prefilling_elapsed_millis=3.305s
prefilling_size=9667674 prefilling_elapsed_millis=3.405s
prefilling_size=9696892 prefilling_elapsed_millis=3.506s
prefilling_size=9722311 prefilling_elapsed_millis=3.606s
prefilling_size=9748351 prefilling_elapsed_millis=3.706s
prefilling_size=9771681 prefilling_elapsed_millis=3.806s
prefilling_size=9792706 prefilling_elapsed_millis=3.906s
prefilling_size=9809324 prefilling_elapsed_millis=4.006s
finished prefilling to size 9809331 for expected size 10000000 keysum=98106935283512, performing 79251452 updates; total_prefilling_elapsed_ms=4107 ms)
pref_size=9809331
pref_millis=4107
prefill_elapsed_ms=4136
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=3610
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6983578.942181074
REALTIME_END_PERF_FORMAT=6983588.942317253
###############################################################################
################################## 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 986 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.141s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 501 967 1839 3404 6039 10396 17349 27937 43721 66263 96866 136568 184703 239887 298256 357480 409864 451515 478216 487016 479328 455180 418256 372767 322176 270494 220517 175967 136444 103238 75819 54094 37301 24942 16161 10092 6212 3684 2114 1266 689 379 168 74 25 8 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 3 19 55 151 422 921 1865 3703 6615 11405 19299 30732 47398 69352 97331 127349 162198 194972 224992 247565 260518 264759 258230 243167 221049 195541 167066 138151 111844 87876 67806 50767 36649 25693 17365 11549 7261 4534 2680 1510 953 504 311 129 61 22 8
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 504 986 1894 3555 6461 11317 19214 31640 50336 77668 116165 167300 232101 309239 395587 484829 572062 646487 703208 734581 739846 719939 676486 615934 543225 466035 387583 314118 248288 191114 143625 104861 73950 50635 33526 21641 13473 8218 4794 2776 1642 883 479 203 86 30 8
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 504 986 1894 3555 6461 11317 19214 31640 50336 77668 116165 167300 232101 309239 395587 484829 572062 646487 703208 734581 739846 719939 676486 615934 543225 466035 387583 314118 248288 191114 143625 104861 73950 50635 33526 21641 13473 8218 4794 2776 1642 883 479 203 86 30 8
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.97647 1.9623 1.94016 1.90602 1.85992 1.8169 1.77918 1.74378 1.70793 1.67441 1.6425 1.60633 1.57103 1.53656 1.50349 1.47164 1.44259 1.41363 1.38932 1.36456 1.34418 1.32522 1.3074 1.29221 1.27675 1.26482 1.25125 1.2415 1.23024 1.22019 1.21132 1.20221 1.18936 1.18031 1.16953 1.16346 1.15623 1.14889 1.13507 1.13809 1.13545 1.11815 1.11325 1.07307 1.05911 1.05814 1 1
tree_stats_height=56
tree_stats_numInternals=6506692
tree_stats_numLeaves=3422350
tree_stats_numNodes=9929042
tree_stats_numKeys=9929042
tree_stats_avgDegreeInternal=1.52597
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34468
tree_stats_avgKeyDepth=29.1759
tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 48960 96768 189312 363648 682560 1240512 2172864 3689088 6074880 9664512 14912256 22303680 32121600 44563392 59373888 75952704 93087168 109835904 124125504 135015936 141039552 142050432 138228288 129885312 118259328 104299200 89478720 74415936 60310656 47671296 36693888 27576000 20133312 14198400 9721920 6436992 4155072 2586816 1577856 920448 532992 315264 169536 91968 38976 16512 5760 1536
tree_stats_sizeInBytes=1906376064
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=12915
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:52 2:1517 4:52019 8:344646 16:48301
[2^00, 2^01]: 52
(2^01, 2^02]: 1517
(2^02, 2^03]: 52019
(2^03, 2^04]: 344646
(2^04, 2^05]: 48301
sum_limbo_reclamation_event_size_total=5057585
count_limbo_reclamation_event_size_by_thread=9303 9303 9302 9301 9303 9303 9301 9303 9303 9303 9303 9303 9303 9302 9303 9303 9303 9303 9302 9303 9303 9303 9303 9302 9302 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303 9303
count_limbo_reclamation_event_size_total=446535
sum_limbo_reclamation_event_count_total=446640
first_thread_announced_epoch_by_thread=25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830 25830
sum_num_inserts_by_thread=212011 212260 214591 214719 212761 211229 212983 213794 216126 216021 212645 211707 213233 213291 215521 216674 214158 211931 209998 212772 214776 215752 212434 209988 212034 212534 214910 214913 213411 211659 211297 212953 215564 215444 212947 210959 212539 213534 216016 215867 214123 211638 210709 212815 214670 214567 211563 210144
sum_num_inserts_total=10238185
sum_num_deletes_by_thread=212003 212013 214696 214281 213940 211055 212167 213476 216051 216511 212903 212511 212165 214317 215913 215705 214111 212125 211173 211740 214494 215853 211918 209800 211620 213167 214308 213934 213378 210959 211707 213199 216054 215299 213983 211386 212868 213500 215727 216211 213157 211688 210903 211028 214268 214615 212093 210744
sum_num_deletes_total=10236717
sum_num_searches_by_thread=3800087 3820300 3870201 3860856 3837959 3795628 3818076 3844387 3893605 3900135 3832932 3816650 3827227 3838879 3886655 3885671 3853987 3823195 3790145 3815937 3852941 3882743 3820502 3784712 3804083 3827189 3860610 3868264 3848371 3796948 3813654 3834843 3886820 3890536 3841548 3810220 3830141 3851712 3879593 3889745 3852069 3822605 3796680 3815201 3860324 3872990 3821646 3783321
sum_num_searches_total=184312523
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4224101 4244573 4299488 4289856 4264660 4217912 4243226 4271657 4325782 4332667 4258480 4240868 4252625 4266487 4318089 4318050 4282256 4247251 4211316 4240449 4282211 4314348 4244854 4204500 4227737 4252890 4289828 4297111 4275160 4219566 4236658 4260995 4318438 4321279 4268478 4232565 4255548 4278746 4311336 4321823 4279349 4245931 4218292 4239044 4289262 4302172 4245302 4204209
average_num_operations_total=4266404
stdev_num_operations_total=5233
sum_num_operations_total=204787425
min_num_operations_total=4204209
max_num_operations_total=4332667
first_time_thread_terminate_by_thread=10000136 10000136 10000137 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000137 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000136 10000137 10000137 10000137 10000136 10000136 10000136 10000137 10000136 10000137 10000136
min_time_thread_terminate_total=10000136
max_time_thread_terminate_total=10000137
first_time_thread_start_by_thread=3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 4 3 3 3 3 3 4 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 4 3 3 3 3 3 4
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=
threads_final_keysum=99303428301493
threads_final_size=9929042
final_keysum=99303428301493
final_size=9929042
validate_result=success
Validation OK.
Structural validation OK.
total_find=184312523
total_rq=0
total_inserts=10238185
total_deletes=10236717
total_updates=20474902
total_queries=184312523
total_ops=204787425
find_throughput=18431252
rq_throughput=0
update_throughput=2047490
query_throughput=18431252
total_throughput=20478742
total find : 184312523
total rq : 0
total inserts : 10238185
total deletes : 10236717
total updates : 20474902
total queries : 184312523
total ops : 204787425
find throughput : 18431252
rq throughput : 0
update throughput : 2047490
query throughput : 18431252
total throughput : 20478742
elapsed milliseconds : 10000
napping milliseconds overtime : 0
PAPI_L2_TCM=32.5075
PAPI_L3_TCM=8.33836
PAPI_TOT_CYC=6534.81
PAPI_TOT_INS=414.462
garbage=910736606929713
total_execution_walltime=14.549s
REALTIME_MAIN_END_PERF_FORMAT=6983589.190142306
[time_cmd_output] time_elapsed_sec=14.84, faults_major=0, faults_minor=545695, mem_maxresident_kb=2184752, user_cputime=705.74, sys_cputime=3.82, percent_cpu=4780%
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