__step=000028
__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.tl2 -nwork 96 -nprefill 96 -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=data000028.txt
__path_data=data/data000028.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992206.868697849
binary=./brown_int_bst_tm_auto.debra.tl2
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=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143
TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.039s
prefilling_size=3978509 prefilling_elapsed_millis=1s
prefilling_size=4279741 prefilling_elapsed_millis=1.101s
prefilling_size=4566847 prefilling_elapsed_millis=1.201s
prefilling_size=4838928 prefilling_elapsed_millis=1.301s
prefilling_size=5096786 prefilling_elapsed_millis=1.401s
prefilling_size=5342486 prefilling_elapsed_millis=1.501s
prefilling_size=5576335 prefilling_elapsed_millis=1.602s
prefilling_size=5798032 prefilling_elapsed_millis=1.702s
prefilling_size=6008903 prefilling_elapsed_millis=1.802s
prefilling_size=6208416 prefilling_elapsed_millis=1.902s
prefilling_size=6399622 prefilling_elapsed_millis=2.002s
prefilling_size=6580649 prefilling_elapsed_millis=2.103s
prefilling_size=6752092 prefilling_elapsed_millis=2.203s
prefilling_size=6914670 prefilling_elapsed_millis=2.303s
prefilling_size=7070471 prefilling_elapsed_millis=2.403s
prefilling_size=7217944 prefilling_elapsed_millis=2.503s
prefilling_size=7357300 prefilling_elapsed_millis=2.604s
prefilling_size=7489557 prefilling_elapsed_millis=2.704s
prefilling_size=7616629 prefilling_elapsed_millis=2.804s
prefilling_size=7738134 prefilling_elapsed_millis=2.904s
prefilling_size=7853158 prefilling_elapsed_millis=3.004s
prefilling_size=7961994 prefilling_elapsed_millis=3.104s
prefilling_size=8067522 prefilling_elapsed_millis=3.205s
prefilling_size=8165636 prefilling_elapsed_millis=3.305s
prefilling_size=8259282 prefilling_elapsed_millis=3.405s
prefilling_size=8348561 prefilling_elapsed_millis=3.505s
prefilling_size=8432438 prefilling_elapsed_millis=3.605s
prefilling_size=8513135 prefilling_elapsed_millis=3.706s
prefilling_size=8590617 prefilling_elapsed_millis=3.806s
prefilling_size=8663232 prefilling_elapsed_millis=3.906s
prefilling_size=8731290 prefilling_elapsed_millis=4.006s
prefilling_size=8797120 prefilling_elapsed_millis=4.106s
prefilling_size=8859022 prefilling_elapsed_millis=4.207s
prefilling_size=8917683 prefilling_elapsed_millis=4.307s
prefilling_size=8973752 prefilling_elapsed_millis=4.407s
prefilling_size=9024793 prefilling_elapsed_millis=4.507s
prefilling_size=9075723 prefilling_elapsed_millis=4.607s
prefilling_size=9123787 prefilling_elapsed_millis=4.707s
prefilling_size=9167970 prefilling_elapsed_millis=4.808s
prefilling_size=9211088 prefilling_elapsed_millis=4.908s
prefilling_size=9251977 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 747982 keys with 96 threads...
tid=0 thread_prefill_amount=89569 percent_done_estimate=1149.6 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=91652 percent_done_estimate=1176.3 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=93735 percent_done_estimate=1203.0 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.157147s
prefilling_size=10000000 prefilling_elapsed_millis=0.157s
finished prefilling to size 10000000 for expected size 10000000 keysum=100007402880398, performing 53465096 updates; total_prefilling_elapsed_ms=157 ms)
pref_size=10000000
pref_millis=157
prefill_elapsed_ms=157
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=633
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6992212.340164720
REALTIME_END_PERF_FORMAT=6992222.340241847
###############################################################################
################################## 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 (96 threads)...
partitioned into 508 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.119s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 507 1006 1963 3752 6927 12390 21633 36019 56880 85582 123094 169103 222840 280321 338581 392326 438225 471846 489864 490553 475714 444720 402936 353495 299542 246787 196355 151190 112509 80637 55630 37100 23978 14742 8835 5059 2871 1576 764 393 203 104 46 24 9 6 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 1 5 29 96 314 785 1732 3951 8299 15329 25847 41363 61681 88201 118009 150972 183159 213605 238634 257485 265218 266027 254847 236577 212848 183496 154660 125339 98437 74845 54778 38387 25844 17165 10633 6456 3715 2157 1254 571 303 150 84 35 21 5 7
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 508 1011 1992 3848 7241 13175 23365 39970 65179 100911 148941 210466 284521 368522 456590 543298 621384 685451 728498 748038 740932 710747 657783 590072 512390 430283 351015 276529 210946 155482 110408 75487 49822 31907 19468 11515 6586 3733 2018 964 506 254 130 59 30 11 7
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 508 1011 1992 3848 7241 13175 23365 39970 65179 100911 148941 210466 284521 368522 456590 543298 621384 685451 728498 748038 740932 710747 657783 590072 512390 430283 351015 276529 210946 155482 110408 75487 49822 31907 19468 11515 6586 3733 2018 964 506 254 130 59 30 11 7
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.99216 1.99213 1.97527 1.94629 1.9067 1.86286 1.83302 1.78481 1.72955 1.67554 1.62787 1.58662 1.54839 1.51203 1.47831 1.44836 1.42161 1.39786 1.37443 1.35439 1.33471 1.31721 1.29977 1.2845 1.26928 1.25516 1.24223 1.22841 1.21609 1.20372 1.19148 1.17985 1.16853 1.15915 1.14812 1.13766 1.13261 1.13088 1.1184 1.09911 1.11722 1.10079 1.10236 1.1 1.10169 1.06667 1.09091 1
tree_stats_height=56
tree_stats_numInternals=6559147
tree_stats_numLeaves=3443356
tree_stats_numNodes=10002503
tree_stats_numKeys=10002503
tree_stats_avgDegreeInternal=1.52497
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34425
tree_stats_avgKeyDepth=28.5128
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=4067
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:2 4:40 8:15178 16:300279 32:13926 64:46
(2^01, 2^02]: 2
(2^02, 2^03]: 40
(2^03, 2^04]: 15178
(2^04, 2^05]: 300279
(2^05, 2^06]: 13926
(2^06, 2^07]: 46
sum_limbo_reclamation_event_size_total=7623241
count_limbo_reclamation_event_size_by_thread=3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3432 3431 3432
count_limbo_reclamation_event_size_total=329471
sum_limbo_reclamation_event_count_total=329734
first_thread_announced_epoch_by_thread=8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134 8134
sum_num_inserts_by_thread=154822 157340 157970 158574 158699 157194 157324 157472 159135 159403 159640 158006 156464 157733 157445 159272 159051 158536 155889 158014 159019 159648 157377 157604 155425 156834 158060 158438 159563 157591 157327 158111 158920 159377 158808 157502 156619 157749 158052 158749 158546 157753 156217 157778 158916 160696 159216 157162 157588 158463 159760 160969 160204 160200 159741 158920 160438 161011 160209 159917 159105 160052 160732 161126 159992 158954 157546 158224 160868 162016 160281 160096 157849 159178 160465 161073 159243 160429 159123 160103 160882 161709 161494 160618 158144 158891 160629 160434 160471 158956 158300 158657 160518 161431 160021 159823
sum_num_inserts_total=15259893
sum_num_deletes_by_thread=155504 157892 158953 159033 159024 156847 156899 156998 159329 159389 159031 157766 157167 156911 158285 158507 158927 157426 156641 157925 158723 159650 158937 157126 154924 157910 158344 158528 158413 156329 156677 156915 159351 159547 158265 158389 156439 157645 158159 159056 158665 158658 156182 157882 157627 159463 158747 158123 157780 158709 159864 160735 159859 159561 158861 159950 161039 162109 161199 161031 159171 160490 160614 161877 160256 158407 159159 158570 161315 161514 161011 160840 158450 159170 160806 160722 160051 159665 158529 159471 161808 161270 161773 159891 158170 160060 161341 161149 160430 159264 158663 158432 161464 162216 160975 160866
sum_num_deletes_total=15271685
sum_num_searches_by_thread=2795904 2829562 2854054 2857392 2847068 2819894 2831727 2833468 2864534 2874662 2862633 2849416 2830740 2830923 2855064 2863302 2861163 2841835 2815411 2838984 2855666 2870379 2851661 2830459 2799314 2821344 2843396 2848583 2860682 2830654 2830193 2838948 2863807 2873779 2853177 2840839 2824636 2839942 2853037 2862526 2860381 2847772 2816899 2833149 2844749 2866725 2854497 2837782 2839392 2856217 2879882 2890749 2874149 2877243 2865781 2869506 2900988 2910097 2898517 2886754 2865086 2876955 2891187 2902771 2891460 2857801 2839692 2864540 2891986 2909403 2889877 2876305 2843932 2868619 2888013 2892930 2873068 2885668 2863024 2880254 2907259 2910426 2901652 2886086 2856483 2875934 2893630 2904203 2894689 2865429 2849791 2862868 2895571 2913309 2892256 2887702
sum_num_searches_total=274811846
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3106230 3144794 3170977 3174999 3164791 3133935 3145950 3147938 3182998 3193454 3181304 3165188 3144371 3145567 3170794 3181081 3179141 3157797 3127941 3154923 3173408 3189677 3167975 3145189 3109663 3136088 3159800 3165549 3178658 3144574 3144197 3153974 3182078 3192703 3170250 3156730 3137694 3155336 3169248 3180331 3177592 3164183 3129298 3148809 3161292 3186884 3172460 3153067 3154760 3173389 3199506 3212453 3194212 3197004 3184383 3188376 3222465 3233217 3219925 3207702 3183362 3197497 3212533 3225774 3211708 3175162 3156397 3181334 3214169 3232933 3211169 3197241 3160231 3186967 3209284 3214725 3192362 3205762 3180676 3199828 3229949 3233405 3224919 3206595 3172797 3194885 3215600 3225786 3215590 3183649 3166754 3179957 3217553 3236956 3213252 3208391
average_num_operations_total=3180660
stdev_num_operations_total=3014
sum_num_operations_total=305343424
min_num_operations_total=3106230
max_num_operations_total=3236956
first_time_thread_terminate_by_thread=10000144 10000080 10000077 10000077 10000080 10000079 10000077 10000077 10000079 10000077 10000077 10000077 10000078 10000077 10000077 10000079 10000077 10000078 10000078 10000080 10000079 10000079 10000077 10000077 10000079 10000080 10000078 10000079 10000077 10000080 10000077 10000077 10000079 10000078 10000077 10000079 10000078 10000077 10000077 10000079 10000077 10000078 10000078 10000077 10000077 10000079 10000077 10000078 10000080 10000079 10000077 10000079 10000078 10000080 10000077 10000080 10000077 10000078 10000078 10000077 10000080 10000079 10000077 10000077 10000080 10000081 10000078 10000079 10000077 10000077 10000077 10000080 10000080 10000079 10000077 10000079 10000077 10000080 10000077 10000077 10000080 10000078 10000078 10000077 10000080 10000079 10000080 10000078 10000079 10000080 10000078 10000079 10000080 10000079 10000079 10000078
min_time_thread_terminate_total=10000077
max_time_thread_terminate_total=10000144
first_time_thread_start_by_thread=10 5 6 5 5 6 5 5 6 5 6 6 5 6 5 6 6 5 5 5 5 5 5 6 5 5 6 5 5 6 5 5 6 5 6 6 5 6 5 6 6 5 5 5 5 5 5 6 6 6 6 6 6 6 6 6 6 6 5 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 5 6 6 6 6 6 6 6 6 6 6 6 6 6
min_time_thread_start_total=5
max_time_thread_start_total=10
sum_duration_all_ops_total=
threads_final_keysum=100035797025303
threads_final_size=10002503
final_keysum=100035797025303
final_size=10002503
validate_result=success
Validation OK.
Structural validation OK.
total_find=274811846
total_rq=0
total_inserts=15259893
total_deletes=15271685
total_updates=30531578
total_queries=274811846
total_ops=305343424
find_throughput=27481184
rq_throughput=0
update_throughput=3053157
query_throughput=27481184
total_throughput=30534342
total find : 274811846
total rq : 0
total inserts : 15259893
total deletes : 15271685
total updates : 30531578
total queries : 274811846
total ops : 305343424
find throughput : 27481184
rq throughput : 0
update throughput : 3053157
query throughput : 27481184
total throughput : 30534342
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=42.7314
PAPI_L3_TCM=6.98241
PAPI_TOT_CYC=7134.52
PAPI_TOT_INS=2740.32
garbage=1373821456881226
total_execution_walltime=15.73s
REALTIME_MAIN_END_PERF_FORMAT=6992222.599714180
TL2 system shutdown:
GCLOCK=0x1FBF350 Starts=358157739 Aborts=776740
Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=15.87, faults_major=0, faults_minor=247882, mem_maxresident_kb=666376, user_cputime=1404.72, sys_cputime=73.81, percent_cpu=9311%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.tl2
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2