__step=000954
__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 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000954.txt
__path_data=data/data000954.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6990436.676853319
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=200000
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.005s
prefilling_size=100203 prefilling_elapsed_millis=1.001s
finished prefilling to size 100195 for expected size 100000 keysum=10010891798, performing 68213912 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=100195
pref_millis=1102
prefill_elapsed_ms=1124
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=3167
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6990437.961957563
REALTIME_END_PERF_FORMAT=6990447.962092166
###############################################################################
################################## 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 985 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.038s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 503 960 1710 2827 4215 5658 6825 7448 7531 6773 5708 4357 3173 2354 1682 1121 679 384 183 87 46 29 26 34 32 36 32 33 27 25 21 21 16 14 12 9 5 4 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 5 25 129 329 792 1554 2440 3365 3922 4355 4125 3674 2913 2064 1544 1176 812 505 304 144 68 38 14 13 18 17 20 12 20 16 18 11 16 9 10 6 5 4 4 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 508 985 1839 3156 5007 7212 9265 10813 11453 11128 9833 8031 6086 4418 3226 2297 1491 889 487 231 114 67 40 47 50 53 52 45 47 41 39 32 32 23 22 15 10 8 5 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 508 985 1839 3156 5007 7212 9265 10813 11453 11128 9833 8031 6086 4418 3226 2297 1491 889 487 231 114 67 40 47 50 53 52 45 47 41 39 32 32 23 22 15 10 8 5 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.98828 1.94882 1.89239 1.7863 1.69075 1.59856 1.50014 1.43044 1.37039 1.31407 1.27498 1.23625 1.21529 1.20457 1.19737 1.19064 1.16108 1.14085 1.11586 1.09856 1.11688 1.18421 1.16418 1.525 1.34043 1.42 1.30189 1.25 1.31111 1.29787 1.34146 1.28205 1.34375 1.21875 1.34783 1.13636 1.06667 1.3 1.125 1 1
tree_stats_height=49
tree_stats_numInternals=65111
tree_stats_numLeaves=34498
tree_stats_numNodes=99609
tree_stats_numKeys=99609
tree_stats_avgDegreeInternal=1.52983
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34633
tree_stats_avgKeyDepth=17.6387
tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 49152 97536 189120 353088 605952 961344 1384704 1778880 2076096 2198976 2136576 1887936 1541952 1168512 848256 619392 441024 286272 170688 93504 44352 21888 12864 7680 9024 9600 10176 9984 8640 9024 7872 7488 6144 6144 4416 4224 2880 1920 1536 960 192
tree_stats_sizeInBytes=19124928
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=34387
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:0 16:2 32:485 64:451760 128:27652 256:5
(2^04, 2^05]: 2
(2^05, 2^06]: 485
(2^06, 2^07]: 451760
(2^07, 2^08]: 27652
(2^08, 2^09]: 5
sum_limbo_reclamation_event_size_total=53922857
count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998
count_limbo_reclamation_event_size_total=479904
sum_limbo_reclamation_event_count_total=1498560
first_thread_announced_epoch_by_thread=68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774 68774
sum_num_inserts_by_thread=6952435 6985568 7041142 7066067 7028878 6961021 6988004 7025506 7096950 7119671 7040256 6984705 7003978 7027915 7092807 7087346 7035684 7001124 6967496 6995488 7054642 7085839 6996482 6923905 6913293 6974941 7037426 7047627 7016723 6954166 6959296 6990246 7089184 7113818 7025055 6975315 6976413 7004383 7064704 7070866 7025649 6996521 6946463 6978282 7040107 7068894 6984724 6944858
sum_num_inserts_total=336761863
sum_num_deletes_by_thread=6945483 6984890 7044242 7070171 7023667 6961696 6984854 7018914 7099325 7119728 7036146 6983182 7005493 7028028 7094053 7089601 7034743 6993857 6964354 6994745 7052534 7082833 6998563 6926697 6915143 6975757 7045457 7049128 7013461 6954450 6963575 6997683 7087477 7116399 7027380 6980362 6976819 7005475 7067628 7074764 7027963 6993279 6946885 6984850 7038855 7069489 6981357 6946631
sum_num_deletes_total=336778066
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=13897918 13970458 14085384 14136238 14052545 13922717 13972858 14044420 14196275 14239399 14076402 13967887 14009471 14055943 14186860 14176947 14070427 13994981 13931850 13990233 14107176 14168672 13995045 13850602 13828436 13950698 14082883 14096755 14030184 13908616 13922871 13987929 14176661 14230217 14052435 13955677 13953232 14009858 14132332 14145630 14053612 13989800 13893348 13963132 14078962 14138383 13966081 13891489
average_num_operations_total=14032081
stdev_num_operations_total=14904
sum_num_operations_total=673539929
min_num_operations_total=13828436
max_num_operations_total=14239399
first_time_thread_terminate_by_thread=10000134 10000133 10000134 10000134 10000135 10000134 10000133 10000133 10000133 10000134 10000134 10000135 10000133 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000133 10000134 10000133 10000134 10000134 10000133 10000133 10000134 10000135 10000134 10000134 10000134 10000133 10000134 10000134 10000135 10000133 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000133 10000134 10000134 10000134
min_time_thread_terminate_total=10000133
max_time_thread_terminate_total=10000135
first_time_thread_start_by_thread=6 6 5 6 5 5 5 6 6 5 5 6 6 6 5 5 6 6 6 6 6 6 5 6 6 6 5 6 5 5 5 6 6 5 5 6 6 6 5 5 6 6 6 6 6 6 5 6
min_time_thread_start_total=5
max_time_thread_start_total=6
sum_duration_all_ops_total=
threads_final_keysum=9967489137
threads_final_size=99609
final_keysum=9967489137
final_size=99609
validate_result=success
Validation OK.
Structural validation OK.
total_find=0
total_rq=0
total_inserts=336761863
total_deletes=336778066
total_updates=673539929
total_queries=0
total_ops=673539929
find_throughput=0
rq_throughput=0
update_throughput=67353992
query_throughput=0
total_throughput=67353992
total find : 0
total rq : 0
total inserts : 336761863
total deletes : 336778066
total updates : 673539929
total queries : 0
total ops : 673539929
find throughput : 0
rq throughput : 0
update throughput : 67353992
query throughput : 0
total throughput : 67353992
elapsed milliseconds : 10000
napping milliseconds overtime : 0
PAPI_L2_TCM=10.2612
PAPI_L3_TCM=0.0044374
PAPI_TOT_CYC=1977.63
PAPI_TOT_INS=478.444
garbage=0
total_execution_walltime=11.419s
REALTIME_MAIN_END_PERF_FORMAT=6990448.96692470
[time_cmd_output] time_elapsed_sec=11.54, faults_major=0, faults_minor=81839, mem_maxresident_kb=329184, user_cputime=563.11, sys_cputime=3.74, percent_cpu=4911%
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