__step=000049
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 180 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" ./brown_sigouin_int_avl_tm_auto.debra.hybridnorec -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=data000049.txt
__path_data=data/data000049.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992751.700385638
binary=./brown_sigouin_int_avl_tm_auto.debra.hybridnorec
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_sigouin_int_avl_tm_auto
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=48
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
Hybrid noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.006s
prefilling_size=776839 prefilling_elapsed_millis=1.001s
prefilling_size=850841 prefilling_elapsed_millis=1.101s
prefilling_size=924543 prefilling_elapsed_millis=1.201s
prefilling_size=997414 prefilling_elapsed_millis=1.301s
prefilling_size=1069768 prefilling_elapsed_millis=1.402s
prefilling_size=1141581 prefilling_elapsed_millis=1.502s
prefilling_size=1213076 prefilling_elapsed_millis=1.602s
prefilling_size=1283895 prefilling_elapsed_millis=1.702s
prefilling_size=1354303 prefilling_elapsed_millis=1.802s
prefilling_size=1423955 prefilling_elapsed_millis=1.903s
prefilling_size=1492936 prefilling_elapsed_millis=2.003s
prefilling_size=1561629 prefilling_elapsed_millis=2.103s
prefilling_size=1629748 prefilling_elapsed_millis=2.203s
prefilling_size=1697143 prefilling_elapsed_millis=2.303s
prefilling_size=1764724 prefilling_elapsed_millis=2.404s
prefilling_size=1831362 prefilling_elapsed_millis=2.504s
prefilling_size=1897300 prefilling_elapsed_millis=2.604s
prefilling_size=1962857 prefilling_elapsed_millis=2.704s
prefilling_size=2027890 prefilling_elapsed_millis=2.804s
prefilling_size=2092815 prefilling_elapsed_millis=2.905s
prefilling_size=2157195 prefilling_elapsed_millis=3.005s
prefilling_size=2221052 prefilling_elapsed_millis=3.105s
prefilling_size=2283905 prefilling_elapsed_millis=3.205s
prefilling_size=2346511 prefilling_elapsed_millis=3.305s
prefilling_size=2408524 prefilling_elapsed_millis=3.406s
prefilling_size=2470254 prefilling_elapsed_millis=3.506s
prefilling_size=2531543 prefilling_elapsed_millis=3.606s
prefilling_size=2592666 prefilling_elapsed_millis=3.706s
prefilling_size=2652833 prefilling_elapsed_millis=3.806s
prefilling_size=2712171 prefilling_elapsed_millis=3.907s
prefilling_size=2771279 prefilling_elapsed_millis=4.007s
prefilling_size=2829853 prefilling_elapsed_millis=4.107s
prefilling_size=2888241 prefilling_elapsed_millis=4.207s
prefilling_size=2946370 prefilling_elapsed_millis=4.307s
prefilling_size=3003428 prefilling_elapsed_millis=4.408s
prefilling_size=3060149 prefilling_elapsed_millis=4.508s
prefilling_size=3116568 prefilling_elapsed_millis=4.608s
prefilling_size=3172777 prefilling_elapsed_millis=4.708s
prefilling_size=3228482 prefilling_elapsed_millis=4.808s
prefilling_size=3283799 prefilling_elapsed_millis=4.909s
prefilling_size=3338440 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 6661480 keys with 48 threads...
tid=0 thread_prefill_amount=70822 percent_done_estimate=51.0 elapsed_s=5 est_remaining_s=11 / 16
tid=0 thread_prefill_amount=74988 percent_done_estimate=54.0 elapsed_s=5 est_remaining_s=10 / 16
tid=0 thread_prefill_amount=79154 percent_done_estimate=57.0 elapsed_s=6 est_remaining_s=10 / 15
tid=0 thread_prefill_amount=83320 percent_done_estimate=60.0 elapsed_s=6 est_remaining_s=9 / 15
tid=0 thread_prefill_amount=87486 percent_done_estimate=63.0 elapsed_s=6 est_remaining_s=8 / 14
tid=0 thread_prefill_amount=91652 percent_done_estimate=66.0 elapsed_s=6 est_remaining_s=7 / 14
tid=0 thread_prefill_amount=95818 percent_done_estimate=69.0 elapsed_s=7 est_remaining_s=7 / 13
tid=0 thread_prefill_amount=99984 percent_done_estimate=72.0 elapsed_s=7 est_remaining_s=6 / 13
tid=0 thread_prefill_amount=104150 percent_done_estimate=75.0 elapsed_s=7 est_remaining_s=5 / 13
tid=0 thread_prefill_amount=108316 percent_done_estimate=78.0 elapsed_s=7 est_remaining_s=5 / 12
tid=0 thread_prefill_amount=112482 percent_done_estimate=81.1 elapsed_s=8 est_remaining_s=4 / 12
tid=0 thread_prefill_amount=116648 percent_done_estimate=84.1 elapsed_s=8 est_remaining_s=3 / 11
tid=0 thread_prefill_amount=120814 percent_done_estimate=87.1 elapsed_s=8 est_remaining_s=3 / 11
tid=0 thread_prefill_amount=124980 percent_done_estimate=90.1 elapsed_s=8 est_remaining_s=2 / 11
tid=0 thread_prefill_amount=129146 percent_done_estimate=93.1 elapsed_s=9 est_remaining_s=1 / 10
tid=0 thread_prefill_amount=133312 percent_done_estimate=96.1 elapsed_s=9 est_remaining_s=1 / 10
tid=0 thread_prefill_amount=137478 percent_done_estimate=99.1 elapsed_s=9 est_remaining_s=0 / 9
tid=0 thread_prefill_amount=141644 percent_done_estimate=102.1 elapsed_s=9 est_remaining_s=-0 / 9
tid=0 thread_prefill_amount=145810 percent_done_estimate=105.1 elapsed_s=10 est_remaining_s=-1 / 9
tid=0 thread_prefill_amount=149976 percent_done_estimate=108.1 elapsed_s=10 est_remaining_s=-2 / 8
tid=0 thread_prefill_amount=154142 percent_done_estimate=111.1 elapsed_s=10 est_remaining_s=-2 / 8
tid=0 thread_prefill_amount=158308 percent_done_estimate=114.1 elapsed_s=10 est_remaining_s=-3 / 8
tid=0 thread_prefill_amount=162474 percent_done_estimate=117.1 elapsed_s=11 est_remaining_s=-4 / 7
tid=0 thread_prefill_amount=166640 percent_done_estimate=120.1 elapsed_s=11 est_remaining_s=-4 / 7
tid=0 thread_prefill_amount=170806 percent_done_estimate=123.1 elapsed_s=11 est_remaining_s=-5 / 6
tid=0 thread_prefill_amount=174972 percent_done_estimate=126.1 elapsed_s=11 est_remaining_s=-5 / 6
tid=0 thread_prefill_amount=179138 percent_done_estimate=129.1 elapsed_s=12 est_remaining_s=-6 / 6
tid=0 thread_prefill_amount=183304 percent_done_estimate=132.1 elapsed_s=12 est_remaining_s=-7 / 5
tid=0 thread_prefill_amount=187470 percent_done_estimate=135.1 elapsed_s=12 est_remaining_s=-7 / 5
tid=0 thread_prefill_amount=191636 percent_done_estimate=138.1 elapsed_s=12 est_remaining_s=-8 / 5
tid=0 thread_prefill_amount=195802 percent_done_estimate=141.1 elapsed_s=13 est_remaining_s=-8 / 4
tid=0 thread_prefill_amount=199968 percent_done_estimate=144.1 elapsed_s=13 est_remaining_s=-9 / 4
tid=0 thread_prefill_amount=204134 percent_done_estimate=147.1 elapsed_s=13 est_remaining_s=-9 / 4
tid=0 thread_prefill_amount=208300 percent_done_estimate=150.1 elapsed_s=13 est_remaining_s=-10 / 3
timing_elapsed 8.10602s
prefilling_size=10000000 prefilling_elapsed_millis=8.125s
finished prefilling to size 10000000 for expected size 10000000 keysum=100026104330754, performing 18344960 updates; total_prefilling_elapsed_ms=8125 ms)
pref_size=10000000
pref_millis=8125
prefill_elapsed_ms=8125
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6992765.168162272
REALTIME_END_PERF_FORMAT=6992775.168240091
###############################################################################
################################## 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.048s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262083 520942 977428 1471265 1402963 690022 141686 8468 75 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 58 2973 56030 399057 1197625 1550242 856112 180109 10558 97
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262141 523915 1033458 1870322 2600588 2240264 997798 188577 10633 97
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262141 523915 1033458 1870322 2600588 2240264 997798 188577 10633 97
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99998 1.99882 1.97824 1.86399 1.60381 1.32197 1.13738 1.04699 1.01148 1.00207 1
tree_stats_height=28
tree_stats_numInternals=5737075
tree_stats_numLeaves=4252861
tree_stats_numNodes=9989936
tree_stats_numKeys=9989936
tree_stats_avgDegreeInternal=1.74129
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42571
tree_stats_avgKeyDepth=21.6711
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0
[2^00, 2^01]: 0
sum_limbo_reclamation_event_size_total=
count_limbo_reclamation_event_size_by_thread=
count_limbo_reclamation_event_size_total=
sum_limbo_reclamation_event_count_total=476975
first_thread_announced_epoch_by_thread=21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810 21810
sum_num_inserts_by_thread=225038 226437 226189 227577 227078 225404 225722 225480 228393 228953 227678 226755 227220 228101 228670 228996 227456 226404 225491 227346 227407 229681 226574 224686 226485 226109 228542 228705 227076 226513 226133 227757 229578 229437 227444 225815 227317 228487 229119 228190 227441 225399 224638 225812 227789 228836 225999 224891
sum_num_inserts_total=10902248
sum_num_deletes_by_thread=224805 226406 228160 227577 226835 226004 225076 226359 228545 228781 226868 225780 226745 227249 229467 229875 226249 226463 225259 226391 227100 228006 226382 225877 226110 225871 228613 228001 227668 225961 225767 227595 228063 229578 226857 226424 225970 226621 228172 228161 227185 226181 225105 226630 227636 228076 225636 224511
sum_num_deletes_total=10892651
sum_num_searches_by_thread=4056072 4077126 4094675 4103561 4090410 4058130 4064401 4078464 4118781 4122711 4075065 4071285 4084493 4091302 4120511 4113364 4085223 4073492 4054708 4078491 4096564 4114073 4077069 4041677 4067711 4066533 4110358 4102510 4092353 4060755 4065433 4083094 4110145 4127086 4088227 4066446 4084785 4098459 4109698 4107670 4091083 4072745 4041368 4066241 4101492 4107629 4069085 4047665
sum_num_searches_total=196080219
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4505915 4529969 4549024 4558715 4544323 4509538 4515199 4530303 4575719 4580445 4529611 4523820 4538458 4546652 4578648 4572235 4538928 4526359 4505458 4532228 4551071 4571760 4530025 4492240 4520306 4518513 4567513 4559216 4547097 4513229 4517333 4538446 4567786 4586101 4542528 4518685 4538072 4553567 4566989 4564021 4545709 4524325 4491111 4518683 4556917 4564541 4520720 4497067
average_num_operations_total=4539064
stdev_num_operations_total=3589
sum_num_operations_total=217875118
min_num_operations_total=4491111
max_num_operations_total=4586101
first_time_thread_terminate_by_thread=10000192 10000077 10000078 10000078 10000079 10000078 10000077 10000077 10000077 10000078 10000077 10000078 10000078 10000078 10000077 10000077 10000078 10000077 10000077 10000078 10000078 10000078 10000078 10000079 10000077 10000078 10000078 10000078 10000078 10000078 10000077 10000078 10000077 10000078 10000077 10000078 10000077 10000078 10000077 10000077 10000077 10000079 10000078 10000077 10000079 10000079 10000078 10000078
min_time_thread_terminate_total=10000077
max_time_thread_terminate_total=10000192
first_time_thread_start_by_thread=8 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=
threads_final_keysum=99915463082342
threads_final_size=9989936
final_keysum=99915463082342
final_size=9989936
validate_result=success
Validation OK.
Structural validation OK.
total_find=196080219
total_rq=0
total_inserts=10902248
total_deletes=10892651
total_updates=21794899
total_queries=196080219
total_ops=217875118
find_throughput=19608021
rq_throughput=0
update_throughput=2179489
query_throughput=19608021
total_throughput=21787511
total find : 196080219
total rq : 0
total inserts : 10902248
total deletes : 10892651
total updates : 21794899
total queries : 196080219
total ops : 217875118
find throughput : 19608021
rq throughput : 0
update throughput : 2179489
query throughput : 19608021
total throughput : 21787511
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=21.4306
PAPI_L3_TCM=7.08841
PAPI_TOT_CYC=6138.61
PAPI_TOT_INS=1271.83
garbage=981601632280869
total_execution_walltime=23.655s
REALTIME_MAIN_END_PERF_FORMAT=6992775.371659937
Hybrid noREC system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 232069151
[slow htm]
[fallback]
total fallback commit : 4150928
seconds global lock is held : 1.34494
[time_cmd_output] time_elapsed_sec=23.85, faults_major=0, faults_minor=263064, mem_maxresident_kb=1052684, user_cputime=1117.83, sys_cputime=5.06, percent_cpu=4707%
timeout=false
algorithm=brown_sigouin_int_avl_tm_auto.debra.hybridnorec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1