__step=000218
__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.norec -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000218.txt
__path_data=data/data000218.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997071.601312250
binary=./brown_int_bst_tm_auto.debra.norec
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=2000000
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
noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.008s
prefilling_size=605019 prefilling_elapsed_millis=1.001s
prefilling_size=640124 prefilling_elapsed_millis=1.101s
prefilling_size=672525 prefilling_elapsed_millis=1.201s
prefilling_size=701958 prefilling_elapsed_millis=1.301s
prefilling_size=728718 prefilling_elapsed_millis=1.402s
prefilling_size=753160 prefilling_elapsed_millis=1.502s
prefilling_size=775276 prefilling_elapsed_millis=1.602s
prefilling_size=795551 prefilling_elapsed_millis=1.702s
prefilling_size=813844 prefilling_elapsed_millis=1.802s
prefilling_size=830705 prefilling_elapsed_millis=1.903s
prefilling_size=845636 prefilling_elapsed_millis=2.003s
prefilling_size=860145 prefilling_elapsed_millis=2.103s
prefilling_size=872293 prefilling_elapsed_millis=2.203s
prefilling_size=883654 prefilling_elapsed_millis=2.303s
prefilling_size=893787 prefilling_elapsed_millis=2.404s
prefilling_size=903197 prefilling_elapsed_millis=2.504s
prefilling_size=911979 prefilling_elapsed_millis=2.604s
prefilling_size=919306 prefilling_elapsed_millis=2.704s
prefilling_size=926543 prefilling_elapsed_millis=2.804s
prefilling_size=932698 prefilling_elapsed_millis=2.905s
prefilling_size=939287 prefilling_elapsed_millis=3.005s
prefilling_size=944867 prefilling_elapsed_millis=3.105s
prefilling_size=949773 prefilling_elapsed_millis=3.205s
prefilling_size=954324 prefilling_elapsed_millis=3.305s
prefilling_size=958007 prefilling_elapsed_millis=3.406s
prefilling_size=961460 prefilling_elapsed_millis=3.506s
prefilling_size=964134 prefilling_elapsed_millis=3.606s
prefilling_size=967373 prefilling_elapsed_millis=3.706s
prefilling_size=969822 prefilling_elapsed_millis=3.806s
prefilling_size=972298 prefilling_elapsed_millis=3.907s
prefilling_size=974612 prefilling_elapsed_millis=4.007s
prefilling_size=976688 prefilling_elapsed_millis=4.107s
prefilling_size=978181 prefilling_elapsed_millis=4.207s
prefilling_size=979893 prefilling_elapsed_millis=4.307s
prefilling_size=981719 prefilling_elapsed_millis=4.408s
finished prefilling to size 981712 for expected size 1000000 keysum=981700554229, performing 8274053 updates; total_prefilling_elapsed_ms=4509 ms)
pref_size=981712
pref_millis=4509
prefill_elapsed_ms=4531
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=384
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6997076.338425328
REALTIME_END_PERF_FORMAT=6997086.338575312
###############################################################################
################################## 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 967 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.035s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 254 493 938 1705 3056 5310 8766 13810 20326 28196 36435 44318 50587 54735 56185 55213 51998 47066 40921 34712 28027 21834 16242 11566 7816 5186 3339 2042 1155 600 294 142 82 40 18 9 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 9 29 94 191 450 1023 2039 4024 6717 10723 15421 20297 24650 28371 30298 30861 30033 27764 24645 21648 17956 14434 10893 7994 5426 3619 2386 1515 876 461 218 100 59 33 12 10
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 254 502 967 1799 3247 5760 9789 15849 24350 34913 47158 59739 70884 79385 84556 85511 82859 77099 68685 59357 49675 39790 30676 22459 15810 10612 6958 4428 2670 1476 755 360 182 99 51 21 10
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 254 502 967 1799 3247 5760 9789 15849 24350 34913 47158 59739 70884 79385 84556 85511 82859 77099 68685 59357 49675 39790 30676 22459 15810 10612 6958 4428 2670 1476 755 360 182 99 51 21 10
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.98438 1.97638 1.94422 1.89038 1.85714 1.83277 1.7776 1.72357 1.66503 1.59906 1.54312 1.49417 1.4447 1.40627 1.37565 1.34682 1.3233 1.30294 1.28041 1.26841 1.25208 1.2368 1.22222 1.20267 1.18897 1.17685 1.16698 1.15651 1.14182 1.12022 1.10501 1.08742 1.11111 1.09341 1.11111 1.05882 1.04762 1
tree_stats_height=45
tree_stats_numInternals=653671
tree_stats_numLeaves=345279
tree_stats_numNodes=998950
tree_stats_numKeys=998950
tree_stats_avgDegreeInternal=1.52822
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34564
tree_stats_avgKeyDepth=23.3513
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=6386
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:38 2:941 4:32099 8:222348 16:32571
[2^00, 2^01]: 38
(2^01, 2^02]: 941
(2^02, 2^03]: 32099
(2^03, 2^04]: 222348
(2^04, 2^05]: 32571
sum_limbo_reclamation_event_size_total=3289249
count_limbo_reclamation_event_size_by_thread=6000 6000 5999 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 5999 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 5999 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000 6000
count_limbo_reclamation_event_size_total=287997
sum_limbo_reclamation_event_count_total=288096
first_thread_announced_epoch_by_thread=12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772 12772
sum_commit_tx_reader_total=125346136
sum_commit_tx_reader_by_thread=0 2605873 2637077 2594665 2607948 2559741 2639920 2595859 2602665 2608333 2645118 2642761 2639084 2583920 2608934 2613542 2623465 2568165 2579254 2642299 2706920 2589837 2612365 2641793 2618029 2629458 2570248 2617853 2600093 2583974 2615499 2623893 2628993 2571073 2605744 2618588 2626488 2568765 2628394 2629884 2611464 2614525 2605756 2612396 2603791 2620302 2611658 2583267 2596463
sum_commit_tx_writer_total=6598438
sum_commit_tx_writer_by_thread=0 137269 139477 136033 137658 134990 138372 136679 137075 137254 139664 138820 139154 135644 137770 137436 138214 135076 135749 139745 142566 136812 136810 139323 137766 137935 135480 138305 137254 136223 137817 136963 137544 135432 136925 137631 138062 135521 138827 138087 136491 138045 137453 137990 136907 138375 137315 135754 136746
sum_abort_tx_total=1569
sum_abort_tx_by_thread=0 42 27 32 36 24 28 27 45 38 31 41 40 37 42 37 30 26 31 35 40 30 29 30 29 35 29 29 36 31 31 27 34 38 26 31 24 28 33 35 35 37 31 35 32 32 37 28 28
max_ht_capacity_by_thread=0 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048
linear_histogram_of_none_tx_rdset_size_full_data=19.9:4 27.8:98 35.7:1181 43.6:6943 51.5:14958 59.4:14464 67.3:7664 75.2:2283 83.1:369 91:35
[ 12.00, 19.90]: 4
( 19.90, 27.80]: 98
( 27.80, 35.70]: 1181
( 35.70, 43.60]: 6943
( 43.60, 51.50]: 14958
( 51.50, 59.40]: 14464
( 59.40, 67.30]: 7664
( 67.30, 75.20]: 2283
( 75.20, 83.10]: 369
( 83.10, 91.00]: 35
linear_histogram_of_none_tx_wrset_size_full_data=1.4:15376 3.4:8066
[ 1.00, 1.40]: 15376
( 3.00, 3.40]: 8066
sum_fasthtm_commit_total=
sum_fasthtm_abort_total=
sum_htmpostfix_commit_total=
sum_htmpostfix_abort_total=
sum_htmprefix_commit_total=
sum_htmprefix_abort_total=
sum_htmprefix_abort_explicit_total=
sum_htmprefix_abort_conflict_total=
sum_htmprefix_abort_capacity_total=
sum_htmprefix_abort_nesting_total=
sum_htmprefix_abort_illegal_total=
sum_htmprefix_abort_zero_total=
sum_stmprefix_abort_total=
sum_stmprefix_commit_total=
sum_slow_commit_total=
sum_abtree_insert_repeat_total=
sum_abtree_erase_repeat_total=
sum_abtree_search_repeat_total=
sum_abtree_search_complete_total=
sum_abtree_fix_weight_viol_attempt_total=
sum_abtree_fix_degree_viol_attempt_total=
sum_abtree_rebalancing_kcas_attempt_total=
sum_abtree_update_kcas_attempt_total=
sum_abtree_internal_complex_search_while_iters_total=
sum_abtree_internal_basic_search_while_iters_total=
sum_kcas_desc_new_total=
sum_num_inserts_by_thread=137051 137354 137210 137012 139627 136383 136627 137390 136984 137835 137264 136794 136022 137735 137903 139456 138274 137572 135052 137344 142281 139037 136374 135403 134526 135826 138434 139688 137837 136073 135576 138142 137845 139198 137792 137645 135935 138577 138130 137589 139736 136756 135015 137313 135576 137379 138268 137007
sum_num_inserts_total=6595847
sum_num_deletes_by_thread=137191 136196 137344 137672 139042 137243 136965 137611 137632 137663 137782 136952 136303 137887 138394 138582 138199 136364 135230 136901 142929 139545 136837 135975 135026 136892 138019 139201 138344 136355 135105 138115 138051 139027 137759 137939 134785 138719 138123 138243 138695 137337 135601 136908 135960 137297 137952 136681
sum_num_deletes_total=6598573
sum_num_searches_by_thread=2471364 2466190 2471033 2472020 2497885 2459583 2458946 2468208 2478700 2474888 2473927 2466952 2447872 2485234 2476273 2503543 2501819 2463411 2432959 2474930 2564276 2503462 2453438 2443625 2425179 2457980 2490940 2505893 2482496 2446593 2435047 2485422 2480323 2502891 2480244 2480574 2433566 2489925 2491718 2490705 2499807 2469049 2435889 2468448 2448028 2476302 2488330 2474267
sum_num_searches_total=118750154
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2745606 2739740 2745587 2746704 2776554 2733209 2732538 2743209 2753316 2750386 2748973 2740698 2720197 2760856 2752570 2781581 2778292 2737347 2703241 2749175 2849486 2782044 2726649 2715003 2694731 2730698 2767393 2784782 2758677 2719021 2705728 2761679 2756219 2781116 2755795 2756158 2704286 2767221 2767971 2766537 2778238 2743142 2706505 2742669 2719564 2750978 2764550 2747955
average_num_operations_total=2748845
stdev_num_operations_total=4005
sum_num_operations_total=131944574
min_num_operations_total=2694731
max_num_operations_total=2849486
first_time_thread_terminate_by_thread=10000149 10000149 10000150 10000151 10000150 10000150 10000150 10000150 10000149 10000149 10000150 10000149 10000151 10000150 10000150 10000150 10000150 10000149 10000151 10000151 10000150 10000151 10000150 10000150 10000150 10000149 10000150 10000151 10000150 10000150 10000150 10000150 10000149 10000149 10000149 10000149 10000151 10000150 10000150 10000151 10000150 10000149 10000151 10000151 10000150 10000151 10000150 10000150
min_time_thread_terminate_total=10000149
max_time_thread_terminate_total=10000151
first_time_thread_start_by_thread=5 5 6 6 5 5 6 6 6 6 6 5 6 5 6 6 5 5 6 6 5 6 5 6 5 5 6 6 5 5 6 6 6 6 6 5 6 5 6 6 5 5 6 6 5 6 5 6
min_time_thread_start_total=5
max_time_thread_start_total=6
sum_duration_all_ops_total=
threads_final_keysum=998788067356
threads_final_size=998950
final_keysum=998788067356
final_size=998950
validate_result=success
Validation OK.
Structural validation OK.
total_find=118750154
total_rq=0
total_inserts=6595847
total_deletes=6598573
total_updates=13194420
total_queries=118750154
total_ops=131944574
find_throughput=11875015
rq_throughput=0
update_throughput=1319442
query_throughput=11875015
total_throughput=13194457
total find : 118750154
total rq : 0
total inserts : 6595847
total deletes : 6598573
total updates : 13194420
total queries : 118750154
total ops : 131944574
find throughput : 11875015
rq throughput : 0
update throughput : 1319442
query throughput : 11875015
total throughput : 13194457
elapsed milliseconds : 10000
napping milliseconds overtime : 0
PAPI_L2_TCM=13.4502
PAPI_L3_TCM=0.226582
PAPI_TOT_CYC=8499.11
PAPI_TOT_INS=6485.17
garbage=59166392892126
total_execution_walltime=15.098s
REALTIME_MAIN_END_PERF_FORMAT=6997086.714981079
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=15.24, faults_major=0, faults_minor=84724, mem_maxresident_kb=340824, user_cputime=698.57, sys_cputime=29.82, percent_cpu=4777%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.norec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2