__step=000034
__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_int_bst_tm_auto.debra.hytm1 -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=data000034.txt
__path_data=data/data000034.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992302.608132635
binary=./brown_int_bst_tm_auto.debra.hytm1
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_int_bst_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=32
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
HyTM1 system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.006s
prefilling_size=3418836 prefilling_elapsed_millis=1.001s
prefilling_size=3705626 prefilling_elapsed_millis=1.101s
prefilling_size=3985297 prefilling_elapsed_millis=1.201s
prefilling_size=4255454 prefilling_elapsed_millis=1.301s
prefilling_size=4523030 prefilling_elapsed_millis=1.402s
prefilling_size=4781024 prefilling_elapsed_millis=1.502s
prefilling_size=5036147 prefilling_elapsed_millis=1.602s
prefilling_size=5293489 prefilling_elapsed_millis=1.702s
prefilling_size=5514678 prefilling_elapsed_millis=1.802s
prefilling_size=5730999 prefilling_elapsed_millis=1.903s
prefilling_size=5942902 prefilling_elapsed_millis=2.003s
prefilling_size=6148767 prefilling_elapsed_millis=2.103s
prefilling_size=6350199 prefilling_elapsed_millis=2.203s
prefilling_size=6545447 prefilling_elapsed_millis=2.303s
prefilling_size=6733819 prefilling_elapsed_millis=2.404s
prefilling_size=6917470 prefilling_elapsed_millis=2.504s
prefilling_size=7095907 prefilling_elapsed_millis=2.604s
prefilling_size=7266737 prefilling_elapsed_millis=2.704s
prefilling_size=7432217 prefilling_elapsed_millis=2.804s
prefilling_size=7592511 prefilling_elapsed_millis=2.905s
prefilling_size=7745617 prefilling_elapsed_millis=3.005s
prefilling_size=7893081 prefilling_elapsed_millis=3.105s
prefilling_size=8034571 prefilling_elapsed_millis=3.205s
prefilling_size=8169088 prefilling_elapsed_millis=3.305s
prefilling_size=8297786 prefilling_elapsed_millis=3.406s
prefilling_size=8419770 prefilling_elapsed_millis=3.506s
prefilling_size=8534866 prefilling_elapsed_millis=3.606s
prefilling_size=8642266 prefilling_elapsed_millis=3.706s
prefilling_size=8745477 prefilling_elapsed_millis=3.806s
prefilling_size=8842093 prefilling_elapsed_millis=3.907s
prefilling_size=8932160 prefilling_elapsed_millis=4.007s
prefilling_size=9018448 prefilling_elapsed_millis=4.107s
prefilling_size=9097196 prefilling_elapsed_millis=4.207s
prefilling_size=9171879 prefilling_elapsed_millis=4.307s
prefilling_size=9241939 prefilling_elapsed_millis=4.408s
prefilling_size=9306315 prefilling_elapsed_millis=4.508s
prefilling_size=9365581 prefilling_elapsed_millis=4.608s
prefilling_size=9419051 prefilling_elapsed_millis=4.708s
prefilling_size=9469528 prefilling_elapsed_millis=4.808s
prefilling_size=9516823 prefilling_elapsed_millis=4.909s
prefilling_size=9560155 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 439807 keys with 48 threads...
tid=0 thread_prefill_amount=208300 percent_done_estimate=2273.4 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=212466 percent_done_estimate=2318.8 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.139825s
prefilling_size=10000000 prefilling_elapsed_millis=0.14s
finished prefilling to size 10000000 for expected size 10000000 keysum=99993232585536, performing 63434686 updates; total_prefilling_elapsed_ms=140 ms)
pref_size=10000000
pref_millis=140
prefill_elapsed_ms=140
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0
global_epoch_counter=2958
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6992308.46435372
REALTIME_END_PERF_FORMAT=6992318.46515774
###############################################################################
################################## 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 245 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.173s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 63 124 243 477 919 1740 3236 5856 10357 17559 28751 45603 69399 101615 142645 190670 244121 298716 350411 395543 429680 449365 454801 445793 425850 396111 359701 319932 278027 237036 197898 162258 129930 101981 77919 58031 42073 29896 20628 13756 9107 5599 3412 2098 1199 697 390 202 96 48 22 10 4 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 2 2 7 18 58 134 345 768 1673 3461 6417 11761 20008 32403 50673 73006 100806 130612 161447 190663 215549 233307 243288 244327 238648 226304 208516 188728 166508 143736 121666 101253 81799 65263 50764 38301 27985 20318 14250 9432 6483 4007 2459 1552 900 525 314 149 75 35 16 9 3 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 63 126 245 484 937 1798 3370 6201 11125 19232 32212 52020 81160 121623 175048 241343 317127 399522 481023 556990 620343 664914 688108 689081 670177 634759 586005 528448 466755 403544 341634 283924 231183 183780 143182 108795 80374 57881 40946 28006 18539 12082 7419 4557 2751 1597 915 516 245 123 57 26 13 4 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 63 126 245 484 937 1798 3370 6201 11125 19232 32212 52020 81160 121623 175048 241343 317127 399522 481023 556990 620343 664914 688108 689081 670177 634759 586005 528448 466755 403544 341634 283924 231183 183780 143182 108795 80374 57881 40946 28006 18539 12082 7419 4557 2751 1597 915 516 245 123 57 26 13 4 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 1.96875 2 1.96032 1.98367 1.95041 1.9381 1.90656 1.87982 1.8497 1.79775 1.76191 1.72237 1.68353 1.64347 1.60378 1.56383 1.52397 1.49003 1.45631 1.42946 1.4036 1.3792 1.35906 1.34047 1.32563 1.31172 1.29916 1.28796 1.27784 1.26891 1.2592 1.25181 1.24276 1.23293 1.22419 1.21564 1.20537 1.19668 1.19091 1.18019 1.17078 1.16047 1.15064 1.15433 1.1433 1.14467 1.13651 1.1377 1.08333 1.1102 1.07317 1.07018 1.11538 1 1 1
tree_stats_height=61
tree_stats_numInternals=6561662
tree_stats_numLeaves=3440734
tree_stats_numNodes=10002396
tree_stats_numKeys=10002396
tree_stats_avgDegreeInternal=1.52437
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34399
tree_stats_avgKeyDepth=29.5397
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0
global_epoch_counter=13346
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:78 2:1571 4:53978 8:370267 16:53938
[2^00, 2^01]: 78
(2^01, 2^02]: 1571
(2^02, 2^03]: 53978
(2^03, 2^04]: 370267
(2^04, 2^05]: 53938
sum_limbo_reclamation_event_size_total=5469646
count_limbo_reclamation_event_size_by_thread=9998 9996 9996 9996 9997 9997 9996 9996 9997 9997 9997 9996 9997 9997 9997 9997 9995 9997 9997 9996 9997 9996 9997 9996 9998 9997 9996 9996 9997 9996 9996 9996 9997 9996 9997 9997 9997 9997 9996 9995 9996 9996 9996 9996 9997 9997 9996 9996
count_limbo_reclamation_event_size_total=479832
sum_limbo_reclamation_event_count_total=498668
first_thread_announced_epoch_by_thread=26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692 26692
sum_num_inserts_by_thread=236258 236242 234152 239666 238678 234893 232198 238562 240572 240715 238075 237047 237078 234823 235476 238428 234011 236733 230456 238924 239098 237896 232526 236963 235604 237876 239176 239059 238823 232037 235056 238907 240516 240155 233202 237024 235988 235843 236982 241170 238580 232051 235310 238305 239986 236308 238465 237277
sum_num_inserts_total=11373170
sum_num_deletes_by_thread=235625 237484 233924 239519 239380 234912 232610 238046 240526 240065 238146 237274 237773 234348 235345 240305 233316 236872 231372 238463 238770 238209 233097 237581 236260 237139 238174 239631 238908 233021 234851 238039 239237 240233 233186 237221 235486 235626 238184 240996 237432 232286 235201 238605 240049 234512 237301 236838
sum_num_deletes_total=11371378
sum_num_searches_by_thread=4249109 4269648 4213916 4304170 4302993 4239918 4180017 4277500 4333007 4337334 4285968 4273006 4277913 4221398 4230615 4312971 4209918 4269182 4162674 4298086 4305898 4290356 4204882 4267836 4253736 4273544 4298883 4301925 4304288 4184788 4223894 4292157 4303774 4318220 4202211 4276476 4244383 4256017 4280168 4334235 4288555 4183100 4233554 4272054 4315842 4234719 4266633 4281379
sum_num_searches_total=204742850
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4720992 4743374 4681992 4783355 4781051 4709723 4644825 4754108 4814105 4818114 4762189 4747327 4752764 4690569 4701436 4791704 4677245 4742787 4624502 4775473 4783766 4766461 4670505 4742380 4725600 4748559 4776233 4780615 4782019 4649846 4693801 4769103 4783527 4798608 4668599 4750721 4715857 4727486 4755334 4816401 4764567 4647437 4704065 4748964 4795877 4705539 4742399 4755494
average_num_operations_total=4739320
stdev_num_operations_total=7086
sum_num_operations_total=227487398
min_num_operations_total=4624502
max_num_operations_total=4818114
first_time_thread_terminate_by_thread=10000138 10000081 10000080 10000080 10000081 10000080 10000080 10000081 10000080 10000081 10000080 10000081 10000082 10000080 10000080 10000080 10000081 10000081 10000080 10000080 10000080 10000080 10000080 10000081 10000081 10000081 10000080 10000080 10000080 10000080 10000080 10000081 10000080 10000081 10000081 10000080 10000080 10000080 10000081 10000080 10000081 10000080 10000080 10000080 10000080 10000081 10000080 10000081
min_time_thread_terminate_total=10000080
max_time_thread_terminate_total=10000138
first_time_thread_start_by_thread=10 5 6 6 5 5 5 5 5 5 5 5 6 5 5 5 5 5 6 5 5 6 5 5 4 5 6 6 5 5 5 5 5 5 5 5 6 5 5 5 5 5 6 5 5 6 5 5
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=
threads_final_keysum=100017095676555
threads_final_size=10002396
final_keysum=100017095676555
final_size=10002396
validate_result=success
Validation OK.
Structural validation OK.
total_find=204742850
total_rq=0
total_inserts=11373170
total_deletes=11371378
total_updates=22744548
total_queries=204742850
total_ops=227487398
find_throughput=20474285
rq_throughput=0
update_throughput=2274454
query_throughput=20474285
total_throughput=22748739
total find : 204742850
total rq : 0
total inserts : 11373170
total deletes : 11371378
total updates : 22744548
total queries : 204742850
total ops : 227487398
find throughput : 20474285
rq throughput : 0
update throughput : 2274454
query throughput : 20474285
total throughput : 22748739
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=19.0465
PAPI_L3_TCM=5.84489
PAPI_TOT_CYC=5882.78
PAPI_TOT_INS=1623.84
garbage=1023749322148986
total_execution_walltime=15.783s
REALTIME_MAIN_END_PERF_FORMAT=6992318.392574761
HyTM1 system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 290762955
[slow htm]
[fallback]
total fallback commit : 159130
seconds global lock is held : 2.24955
[time_cmd_output] time_elapsed_sec=15.95, faults_major=0, faults_minor=234280, mem_maxresident_kb=632924, user_cputime=739.44, sys_cputime=2.69, percent_cpu=4651%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.hytm1
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2