__step=000732
__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 128 -nprefill 128 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000732.txt
__path_data=data/data000732.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=215675.67341102
binary=./drachsler_pext_bst_lock.debra
parsed custom binding: 0-63,128-191,64-127,192-255
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=128
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=128
WORK_THREADS=128
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167,168,169,170,171,172,173,174,175,176,177,178,179,180,181,182,183,184,185,186,187,188,189,190,191
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.029s
prefilling_size=8602265 prefilling_elapsed_millis=1s
prefilling_size=8821204 prefilling_elapsed_millis=1.1s
prefilling_size=9007586 prefilling_elapsed_millis=1.201s
prefilling_size=9161867 prefilling_elapsed_millis=1.301s
prefilling_size=9288566 prefilling_elapsed_millis=1.401s
prefilling_size=9398099 prefilling_elapsed_millis=1.501s
prefilling_size=9487813 prefilling_elapsed_millis=1.601s
prefilling_size=9566744 prefilling_elapsed_millis=1.701s
prefilling_size=9633309 prefilling_elapsed_millis=1.801s
prefilling_size=9687384 prefilling_elapsed_millis=1.901s
prefilling_size=9734200 prefilling_elapsed_millis=2.002s
prefilling_size=9774139 prefilling_elapsed_millis=2.102s
prefilling_size=9808112 prefilling_elapsed_millis=2.202s
finished prefilling to size 9808136 for expected size 10000000 keysum=98110878174021, performing 78909120 updates; total_prefilling_elapsed_ms=2303 ms)
pref_size=9808136
pref_millis=2303
prefill_elapsed_ms=2315
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=523
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=215677.440824586
REALTIME_END_PERF_FORMAT=215687.440899003
###############################################################################
################################## 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 (256 threads)...
partitioned into 1929 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.176s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 503 984 1883 3554 6513 11533 19697 32440 51523 78116 113898 158962 212603 271779 332729 391055 440470 478152 499676 502030 485902 453800 409912 357899 302632 247719 195917 149523 110763 78989 54288 35869 22824 13587 7827 4331 2313 1171 626 340 179 103 58 35 20 8 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 6 14 46 107 350 824 1848 3830 7351 13631 22925 37204 56858 82533 112633 146591 181837 213796 242288 262753 273570 272774 261523 242183 216487 186733 156160 126600 97993 74234 54036 37729 25349 16552 10021 5767 3272 1771 883 455 256 139 67 42 21 14 7 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 509 998 1929 3661 6863 12357 21545 36270 58874 91747 136823 196166 269461 354312 445362 537646 622307 691948 741964 764783 759472 726574 671435 600082 519119 434452 352077 276123 208756 153223 108324 73598 48173 30139 17848 10098 5585 2942 1509 795 435 242 125 77 41 22 9 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 509 998 1929 3661 6863 12357 21545 36270 58874 91747 136823 196166 269461 354312 445362 537646 622307 691948 741964 764783 759472 726574 671435 600082 519119 434452 352077 276123 208756 153223 108324 73598 48173 30139 17848 10098 5585 2942 1509 795 435 242 125 77 41 22 9 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99219 1.9725 1.94689 1.92172 1.90385 1.85152 1.81023 1.76923 1.72881 1.68322 1.63988 1.60127 1.56329 1.5259 1.48992 1.46011 1.43012 1.40411 1.38126 1.3573 1.33662 1.31689 1.29954 1.28323 1.26866 1.25393 1.24021 1.22781 1.21452 1.2034 1.19145 1.17826 1.16718 1.15185 1.14138 1.12724 1.12418 1.11262 1.11489 1.11199 1.1195 1.14483 1.09091 1.152 1.07792 1.04878 1.04545 1 1
tree_stats_height=57
tree_stats_numInternals=6545247
tree_stats_numLeaves=3452066
tree_stats_numNodes=9997313
tree_stats_numKeys=9997313
tree_stats_avgDegreeInternal=1.52742
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.3453
tree_stats_avgKeyDepth=28.5812
tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 49152 97728 191616 370368 702912 1317696 2372544 4136640 6963840 11303808 17615424 26270016 37663872 51736512 68027904 85509504 103228032 119482944 132854016 142457088 146838336 145818624 139502208 128915520 115215744 99670848 83414784 67598784 53015616 40081152 29418816 20798208 14130816 9249216 5786688 3426816 1938816 1072320 564864 289728 152640 83520 46464 24000 14784 7872 4224 1728 384
tree_stats_sizeInBytes=1919484096
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=2692
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:1 16:0 32:0 64:5 128:9048 256:268212 512:109
(2^03, 2^04]: 1
(2^04, 2^05]: 0
(2^05, 2^06]: 0
(2^06, 2^07]: 5
(2^07, 2^08]: 9048
(2^08, 2^09]: 268212
(2^09, 2^10]: 109
sum_limbo_reclamation_event_size_total=80662292
count_limbo_reclamation_event_size_by_thread=2167 2167 2167 2166 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167 2167
count_limbo_reclamation_event_size_total=277375
sum_limbo_reclamation_event_count_total=277632
first_thread_announced_epoch_by_thread=5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384 5384
sum_num_inserts_by_thread=1269113 1272532 1265102 1263576 1263451 1265385 1265504 1259963 1269523 1268207 1259807 1259742 1268009 1263042 1262356 1255502 1259766 1262438 1258004 1254099 1255590 1270977 1265214 1266569 1270837 1267143 1269315 1267553 1265258 1259209 1258534 1250908 1274474 1251130 1274326 1271169 1270921 1275769 1273785 1267309 1267884 1269276 1264044 1257404 1269212 1268088 1254651 1255764 1260796 1263747 1251177 1251765 1270889 1254117 1267273 1264537 1265083 1260326 1253425 1254327 1263747 1262863 1243230 1262448 1253919 1272295 1252248 1270103 1259826 1271163 1267775 1255040 1259443 1256223 1256931 1258334 1260940 1257351 1263749 1257502 1261258 1265579 1264937 1259979 1261054 1262535 1249460 1259676 1248853 1246476 1262067 1259642 1260583 1262202 1262875 1256098 1280210 1277276 1278025 1278935 1259691 1268210 1264399 1257118 1262286 1257060 1258733 1266907 1270230 1272345 1270786 1262185 1271617 1269030 1260340 1260684 1268176 1251885 1264283 1267982 1263263 1265401 1263003 1261659 1274163 1274941 1265243 1268495
sum_num_inserts_total=161697861
sum_num_deletes_by_thread=1269520 1271619 1264783 1263314 1262753 1265154 1264857 1260313 1269743 1267886 1260128 1259035 1267984 1262677 1259368 1255719 1257434 1263495 1258338 1254061 1254868 1270632 1265831 1267452 1269173 1266056 1268908 1270262 1265042 1259337 1258759 1253065 1276413 1250881 1274515 1271492 1272407 1274668 1272162 1268496 1267351 1267266 1262504 1256444 1267415 1267786 1256557 1256727 1263314 1263337 1254405 1254478 1269660 1256149 1265473 1266631 1264055 1256366 1255372 1253737 1264023 1266288 1243697 1261460 1257179 1269366 1251912 1268924 1262033 1268032 1268192 1251618 1263277 1254549 1258009 1257140 1261443 1257685 1264908 1258954 1259684 1268311 1265880 1260502 1262407 1263587 1249940 1262649 1246810 1247036 1260763 1262392 1259799 1261138 1261789 1257529 1280646 1278335 1278061 1277781 1257329 1269125 1261998 1259417 1261888 1258127 1257602 1267076 1271170 1273610 1268062 1262901 1268269 1269242 1258881 1261328 1267263 1254514 1267645 1265540 1263022 1267209 1263355 1261509 1275421 1272411 1268311 1271182
sum_num_deletes_total=161710762
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2538633 2544151 2529885 2526890 2526204 2530539 2530361 2520276 2539266 2536093 2519935 2518777 2535993 2525719 2521724 2511221 2517200 2525933 2516342 2508160 2510458 2541609 2531045 2534021 2540010 2533199 2538223 2537815 2530300 2518546 2517293 2503973 2550887 2502011 2548841 2542661 2543328 2550437 2545947 2535805 2535235 2536542 2526548 2513848 2536627 2535874 2511208 2512491 2524110 2527084 2505582 2506243 2540549 2510266 2532746 2531168 2529138 2516692 2508797 2508064 2527770 2529151 2486927 2523908 2511098 2541661 2504160 2539027 2521859 2539195 2535967 2506658 2522720 2510772 2514940 2515474 2522383 2515036 2528657 2516456 2520942 2533890 2530817 2520481 2523461 2526122 2499400 2522325 2495663 2493512 2522830 2522034 2520382 2523340 2524664 2513627 2560856 2555611 2556086 2556716 2517020 2537335 2526397 2516535 2524174 2515187 2516335 2533983 2541400 2545955 2538848 2525086 2539886 2538272 2519221 2522012 2535439 2506399 2531928 2533522 2526285 2532610 2526358 2523168 2549584 2547352 2533554 2539677
average_num_operations_total=2526629
stdev_num_operations_total=1233
sum_num_operations_total=323408623
min_num_operations_total=2486927
max_num_operations_total=2560856
first_time_thread_terminate_by_thread=10000076 10000076 10000076 10000076 10000074 10000077 10000074 10000074 10000074 10000077 10000077 10000074 10000077 10000077 10000077 10000077 10000077 10000077 10000077 10000077 10000076 10000076 10000074 10000073 10000078 10000075 10000078 10000078 10000076 10000076 10000076 10000076 10000075 10000075 10000076 10000075 10000077 10000077 10000074 10000074 10000076 10000075 10000078 10000075 10000077 10000077 10000077 10000077 10000076 10000076 10000079 10000076 10000077 10000074 10000074 10000077 10000077 10000077 10000077 10000079 10000075 10000076 10000076 10000075 10000076 10000076 10000076 10000076 10000078 10000074 10000078 10000074 10000077 10000074 10000074 10000074 10000077 10000077 10000077 10000077 10000077 10000077 10000084 10000077 10000076 10000076 10000076 10000076 10000075 10000075 10000078 10000075 10000076 10000076 10000083 10000076 10000075 10000075 10000076 10000075 10000075 10000077 10000077 10000074 10000075 10000081 10000078 10000076 10000080 10000077 10000077 10000077 10000076 10000076 10000076 10000076 10000074 10000074 10000074 10000077 10000077 10000077 10000077 10000077 10000078 10000107 10000076 10000075
min_time_thread_terminate_total=10000073
max_time_thread_terminate_total=10000107
first_time_thread_start_by_thread=8 8 8 4437 10 10 10 10 8 8 8 8 8 8 8 8 11 11 11 11 11 11 11 11 9 9 9 9 11 11 11 11 9 9 9 9 8 8 9 8 10 10 10 10 9 9 9 9 10 10 11 11 10 10 10 10 12 12 12 12 9 9 9 9 8 8 8 8 10 10 10 10 8 8 8 8 8 8 8 8 11 11 11 11 11 11 11 11 9 9 9 9 11 11 11 11 9 9 9 9 8 8 8 8 10 10 10 10 9 9 9 9 10 10 11 11 10 10 10 10 12 12 12 12 9 9 9 9
min_time_thread_start_total=8
max_time_thread_start_total=4437
sum_duration_all_ops_total=
threads_final_keysum=99976916149314
threads_final_size=9997313
final_keysum=99976916149314
final_size=9997313
validate_result=success
Validation OK.
Structural validation OK.
total_find=0
total_rq=0
total_inserts=161697861
total_deletes=161710762
total_updates=323408623
total_queries=0
total_ops=323408623
find_throughput=0
rq_throughput=0
update_throughput=32340862
query_throughput=0
total_throughput=32340862
total find : 0
total rq : 0
total inserts : 161697861
total deletes : 161710762
total updates : 323408623
total queries : 0
total ops : 323408623
find throughput : 0
rq throughput : 0
update throughput : 32340862
query throughput : 0
total throughput : 32340862
elapsed milliseconds : 10000
napping milliseconds overtime : 100
garbage=0
total_execution_walltime=12.68s
REALTIME_MAIN_END_PERF_FORMAT=215687.748404957
[time_cmd_output] time_elapsed_sec=12.92, faults_major=0, faults_minor=560633, mem_maxresident_kb=2239812, user_cputime=1555.97, sys_cputime=21.55, percent_cpu=12205%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2