__step=000617
__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 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000617.txt
__path_data=data/data000617.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6986293.889358874
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=200000
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.019s
prefilling_size=100126 prefilling_elapsed_millis=1.001s
finished prefilling to size 100092 for expected size 100000 keysum=9993175163, performing 68113952 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=100092
pref_millis=1102
prefill_elapsed_ms=1120
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=3152
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6986295.153608052
REALTIME_END_PERF_FORMAT=6986305.153749064
###############################################################################
################################## 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 852 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.033s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 125 240 451 778 1287 1984 2846 3805 4590 5168 5416 5347 5208 4789 4433 4066 3559 3117 2627 2076 1456 938 518 270 110 46 18 6 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 6 16 74 157 342 589 1031 1632 2136 2571 2894 2911 3002 2738 2500 2409 2109 1860 1679 1406 1008 676 391 230 87 34 17 7 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 126 246 467 852 1444 2326 3435 4836 6222 7304 7987 8241 8119 7791 7171 6566 5968 5226 4487 3755 2862 1946 1194 661 340 133 52 23 8 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 126 246 467 852 1444 2326 3435 4836 6222 7304 7987 8241 8119 7791 7171 6566 5968 5226 4487 3755 2862 1946 1194 661 340 133 52 23 8 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.96875 1.96032 1.92276 1.85867 1.78169 1.71953 1.62382 1.57933 1.49979 1.43619 1.38595 1.3537 1.33637 1.31814 1.30574 1.29745 1.28967 1.27932 1.26215 1.25139 1.20932 1.17121 1.13155 1.11977 1.1059 1.06765 1.04511 1.09615 1.08696 1 1
tree_stats_height=37
tree_stats_numInternals=65402
tree_stats_numLeaves=34514
tree_stats_numNodes=99916
tree_stats_numKeys=99916
tree_stats_avgDegreeInternal=1.52772
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34543
tree_stats_avgKeyDepth=19.4976
tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24192 47232 89664 163584 277248 446592 659520 928512 1194624 1402368 1533504 1582272 1558848 1495872 1376832 1260672 1145856 1003392 861504 720960 549504 373632 229248 126912 65280 25536 9984 4416 1536 192
tree_stats_sizeInBytes=19183872
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=45183
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:58 2:1351 4:49996 8:371190 16:57299 32:5
[2^00, 2^01]: 58
(2^01, 2^02]: 1351
(2^02, 2^03]: 49996
(2^03, 2^04]: 371190
(2^04, 2^05]: 57299
(2^05, 2^06]: 5
sum_limbo_reclamation_event_size_total=5533262
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 9997 9998 9997 9998 9998 9998 9998 9998 9998 9998 9997 9998 9997 9998 9998 9998 9997 9998 9998
count_limbo_reclamation_event_size_total=479899
sum_limbo_reclamation_event_count_total=2017487
first_thread_announced_epoch_by_thread=90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366 90366
sum_num_inserts_by_thread=961635 963865 970092 966888 970159 956917 962279 969478 973827 978146 971340 968694 967104 973457 978651 977283 979503 971922 967463 969485 975206 974066 967348 962556 958553 968306 970936 975739 971597 960976 969635 966453 975730 981668 966091 963190 965492 967768 980421 973948 973354 967942 966348 967904 976895 982024 969613 961120
sum_num_inserts_total=46559067
sum_num_deletes_by_thread=961459 964405 971487 969169 970487 956023 960960 971898 974623 978290 969485 967332 967518 971609 979641 980994 978442 973514 968114 969549 976576 975945 968507 966176 959694 967628 970236 974954 970380 959827 969485 967960 977305 981031 967281 965601 965428 968119 979621 971596 974967 967443 966218 968388 976441 982186 970601 961316
sum_num_deletes_total=46575909
sum_num_searches_by_thread=17301257 17348673 17474408 17441915 17471871 17192472 17300241 17478557 17543516 17615868 17478860 17421228 17397679 17497544 17627103 17631480 17611279 17513276 17433475 17421229 17567470 17559319 17416163 17362532 17274861 17414550 17473310 17542043 17493609 17287861 17440339 17417447 17563436 17679369 17413296 17366689 17380096 17421051 17630211 17533156 17540403 17422950 17366011 17418361 17594661 17643655 17454867 17318156
sum_num_searches_total=838197803
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=19224351 19276943 19415987 19377972 19412517 19105412 19223480 19419933 19491966 19572304 19419685 19357254 19332301 19442610 19585395 19589757 19569224 19458712 19369052 19360263 19519252 19509330 19352018 19291264 19193108 19350484 19414482 19492736 19435586 19208664 19379459 19351860 19516471 19642068 19346668 19295480 19311016 19356938 19590253 19478700 19488724 19358335 19298577 19354653 19547997 19607865 19395081 19240592
average_num_operations_total=19402766
stdev_num_operations_total=17829
sum_num_operations_total=931332779
min_num_operations_total=19105412
max_num_operations_total=19642068
first_time_thread_terminate_by_thread=10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140
min_time_thread_terminate_total=10000140
max_time_thread_terminate_total=10000140
first_time_thread_start_by_thread=3 2 3 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 2 3 3 3 3 3 2 3 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 2 3 3 3 3
min_time_thread_start_total=2
max_time_thread_start_total=3
sum_duration_all_ops_total=
threads_final_keysum=9994469184
threads_final_size=99916
final_keysum=9994469184
final_size=99916
validate_result=success
Validation OK.
Structural validation OK.
total_find=838197803
total_rq=0
total_inserts=46559067
total_deletes=46575909
total_updates=93134976
total_queries=838197803
total_ops=931332779
find_throughput=83819780
rq_throughput=0
update_throughput=9313497
query_throughput=83819780
total_throughput=93133277
total find : 838197803
total rq : 0
total inserts : 46559067
total deletes : 46575909
total updates : 93134976
total queries : 838197803
total ops : 931332779
find throughput : 83819780
rq throughput : 0
update throughput : 9313497
query throughput : 83819780
total throughput : 93133277
elapsed milliseconds : 10000
napping milliseconds overtime : 0
PAPI_L2_TCM=4.53182
PAPI_L3_TCM=0.00142417
PAPI_TOT_CYC=1430.5
PAPI_TOT_INS=337.421
garbage=41896339552744
total_execution_walltime=11.394s
REALTIME_MAIN_END_PERF_FORMAT=6986305.284615179
[time_cmd_output] time_elapsed_sec=11.52, faults_major=0, faults_minor=81212, mem_maxresident_kb=326292, user_cputime=558.23, sys_cputime=1.48, percent_cpu=4858%
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