__step=000067 __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.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=data000067.txt __path_data=data/data000067.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6993742.672577050 binary=./brown_sigouin_int_avl_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_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=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=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,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.014s prefilling_size=3907934 prefilling_elapsed_millis=1.001s prefilling_size=4203453 prefilling_elapsed_millis=1.101s prefilling_size=4486140 prefilling_elapsed_millis=1.201s prefilling_size=4752416 prefilling_elapsed_millis=1.301s prefilling_size=5004864 prefilling_elapsed_millis=1.402s prefilling_size=5248980 prefilling_elapsed_millis=1.502s prefilling_size=5478313 prefilling_elapsed_millis=1.602s prefilling_size=5699015 prefilling_elapsed_millis=1.702s prefilling_size=5908570 prefilling_elapsed_millis=1.802s prefilling_size=6105569 prefilling_elapsed_millis=1.903s prefilling_size=6291838 prefilling_elapsed_millis=2.003s prefilling_size=6471379 prefilling_elapsed_millis=2.103s prefilling_size=6642024 prefilling_elapsed_millis=2.203s prefilling_size=6804109 prefilling_elapsed_millis=2.303s prefilling_size=6957000 prefilling_elapsed_millis=2.404s prefilling_size=7102261 prefilling_elapsed_millis=2.504s prefilling_size=7240704 prefilling_elapsed_millis=2.604s prefilling_size=7374185 prefilling_elapsed_millis=2.704s prefilling_size=7499717 prefilling_elapsed_millis=2.804s prefilling_size=7620056 prefilling_elapsed_millis=2.905s prefilling_size=7734292 prefilling_elapsed_millis=3.005s prefilling_size=7844129 prefilling_elapsed_millis=3.105s prefilling_size=7948158 prefilling_elapsed_millis=3.205s prefilling_size=8046446 prefilling_elapsed_millis=3.305s prefilling_size=8140852 prefilling_elapsed_millis=3.406s prefilling_size=8230148 prefilling_elapsed_millis=3.506s prefilling_size=8315116 prefilling_elapsed_millis=3.606s prefilling_size=8394968 prefilling_elapsed_millis=3.706s prefilling_size=8472092 prefilling_elapsed_millis=3.806s prefilling_size=8544328 prefilling_elapsed_millis=3.907s prefilling_size=8613610 prefilling_elapsed_millis=4.007s prefilling_size=8679166 prefilling_elapsed_millis=4.107s prefilling_size=8742218 prefilling_elapsed_millis=4.207s prefilling_size=8801163 prefilling_elapsed_millis=4.307s prefilling_size=8858623 prefilling_elapsed_millis=4.408s prefilling_size=8914148 prefilling_elapsed_millis=4.508s prefilling_size=8966211 prefilling_elapsed_millis=4.608s prefilling_size=9015369 prefilling_elapsed_millis=4.708s prefilling_size=9062527 prefilling_elapsed_millis=4.808s prefilling_size=9108073 prefilling_elapsed_millis=4.909s prefilling_size=9149801 prefilling_elapsed_millis=5.009s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 850150 keys with 96 threads... tid=0 thread_prefill_amount=108316 percent_done_estimate=1223.1 elapsed_s=5 est_remaining_s=-11 / -5 tid=0 thread_prefill_amount=110399 percent_done_estimate=1246.6 elapsed_s=5 est_remaining_s=-11 / -6 tid=0 thread_prefill_amount=112482 percent_done_estimate=1270.2 elapsed_s=5 est_remaining_s=-11 / -6 tid=0 thread_prefill_amount=114565 percent_done_estimate=1293.7 elapsed_s=5 est_remaining_s=-11 / -6 timing_elapsed 0.185781s prefilling_size=10000000 prefilling_elapsed_millis=0.186s finished prefilling to size 10000000 for expected size 10000000 keysum=99976839324744, performing 51603821 updates; total_prefilling_elapsed_ms=186 ms) pref_size=10000000 pref_millis=186 prefill_elapsed_ms=186 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6993748.160165517 REALTIME_END_PERF_FORMAT=6993758.160248113 ############################################################################### ################################## 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 512 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.027s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262129 521835 985451 1488294 1397345 673052 138696 8568 44 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 15 2221 51085 400554 1229320 1551457 834610 175785 10673 49 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524056 1036536 1888848 2626665 2224509 973306 184353 10717 49 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524056 1036536 1888848 2626665 2224509 973306 184353 10717 49 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99917 1.98215 1.87155 1.60268 1.31491 1.13498 1.04691 1.01166 1.00047 1 tree_stats_height=28 tree_stats_numInternals=5737557 tree_stats_numLeaves=4255769 tree_stats_numNodes=9993326 tree_stats_numKeys=9993326 tree_stats_avgDegreeInternal=1.74174 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42586 tree_stats_avgKeyDepth=21.661 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=318693 first_thread_announced_epoch_by_thread=7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 7852 sum_num_inserts_by_thread=150874 151624 152854 152999 152678 151140 151556 152337 154322 154269 153283 152381 151159 152252 152884 154006 153188 152733 150346 152168 153411 153572 153298 152225 150863 151374 153036 153508 152604 151597 151065 152206 153284 154026 152880 152055 151531 152055 153226 153357 153498 152834 151136 152000 153235 154130 152975 151730 150331 151653 153079 153311 152952 151868 150656 152246 154213 153946 152565 151160 151816 152261 152836 154028 153306 150914 150180 150987 153437 153785 153034 152413 150022 152030 153038 153064 152498 152475 151608 151371 153620 154236 152930 152733 150844 151728 153109 153780 152424 151216 150754 151863 153244 152810 152221 152026 sum_num_inserts_total=14634385 sum_num_deletes_by_thread=150718 151286 153088 152788 152655 151356 152203 152159 153842 153627 153280 153372 151837 152736 153262 153632 154131 152364 150794 151964 153081 153598 153311 152602 151228 151442 152812 153079 153432 151542 151665 152759 153898 154106 153199 152487 151679 152632 153006 153811 153200 152366 150604 152492 152344 153473 153780 151574 150416 151684 152820 152833 152717 152285 150818 151840 153213 154128 153018 152250 151383 152887 152870 153518 153046 150786 150553 151043 153533 154081 152530 151542 149961 151917 152834 153684 152692 151585 150760 151646 153900 153460 152839 151743 151351 151854 152578 154037 152398 150500 150332 150638 152968 153779 152519 151914 sum_num_deletes_total=14633979 sum_num_searches_by_thread=2713901 2729129 2754235 2747124 2756198 2726547 2731932 2742977 2770539 2775614 2760719 2742668 2730644 2746120 2764252 2769606 2761612 2743468 2716266 2730018 2757362 2767160 2751188 2730049 2715633 2730278 2752509 2750806 2757687 2731309 2727203 2742935 2768032 2775244 2753068 2737853 2728287 2741008 2760892 2767289 2767142 2744328 2715505 2734994 2753873 2769832 2749579 2734283 2708840 2726442 2748045 2758335 2742679 2740172 2716520 2734233 2761509 2773274 2754395 2737289 2723090 2740595 2754184 2760262 2752950 2720269 2709015 2722162 2758053 2763901 2745761 2731520 2711058 2731702 2750332 2754807 2746579 2745563 2722986 2735958 2764609 2765660 2756266 2737785 2729215 2741804 2753986 2761426 2750196 2719145 2704694 2722704 2759488 2765436 2754040 2737295 sum_num_searches_total=263435126 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3015493 3032039 3060177 3052911 3061531 3029043 3035691 3047473 3078703 3083510 3067282 3048421 3033640 3051108 3070398 3077244 3068931 3048565 3017406 3034150 3063854 3074330 3057797 3034876 3017724 3033094 3058357 3057393 3063723 3034448 3029933 3047900 3075214 3083376 3059147 3042395 3031497 3045695 3067124 3074457 3073840 3049528 3017245 3039486 3059452 3077435 3056334 3037587 3009587 3029779 3053944 3064479 3048348 3044325 3017994 3038319 3068935 3081348 3059978 3040699 3026289 3045743 3059890 3067808 3059302 3021969 3009748 3024192 3065023 3071767 3051325 3035475 3011041 3035649 3056204 3061555 3051769 3049623 3025354 3038975 3072129 3073356 3062035 3042261 3031410 3045386 3059673 3069243 3055018 3020861 3005780 3025205 3065700 3072025 3058780 3041235 average_num_operations_total=3048994 stdev_num_operations_total=1998 sum_num_operations_total=292703490 min_num_operations_total=3005780 max_num_operations_total=3083510 first_time_thread_terminate_by_thread=10000157 10000084 10000084 10000083 10000084 10000082 10000082 10000083 10000084 10000085 10000082 10000082 10000084 10000085 10000084 10000084 10000082 10000084 10000093 10000082 10000082 10000084 10000084 10000082 10000083 10000084 10000082 10000082 10000082 10000082 10000082 10000084 10000085 10000084 10000082 10000082 10000082 10000085 10000085 10000083 10000085 10000084 10000084 10000083 10000082 10000084 10000084 10000082 10000083 10000085 10000083 10000083 10000083 10000083 10000083 10000085 10000083 10000082 10000083 10000083 10000082 10000082 10000083 10000083 10000083 10000082 10000083 10000083 10000083 10000083 10000083 10000083 10000083 10000085 10000085 10000083 10000083 10000083 10000082 10000082 10000082 10000082 10000083 10000083 10000082 10000082 10000083 10000082 10000087 10000082 10000083 10000083 10000083 10000084 10000083 10000083 min_time_thread_terminate_total=10000082 max_time_thread_terminate_total=10000157 first_time_thread_start_by_thread=10 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 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 6 6 6 6 6 6 6 6 6 6 5 min_time_thread_start_total=4 max_time_thread_start_total=10 sum_duration_all_ops_total= threads_final_keysum=99948446117185 threads_final_size=9993326 final_keysum=99948446117185 final_size=9993326 validate_result=success Validation OK. Structural validation OK. total_find=263435126 total_rq=0 total_inserts=14634385 total_deletes=14633979 total_updates=29268364 total_queries=263435126 total_ops=292703490 find_throughput=26343512 rq_throughput=0 update_throughput=2926836 query_throughput=26343512 total_throughput=29270349 total find : 263435126 total rq : 0 total inserts : 14634385 total deletes : 14633979 total updates : 29268364 total queries : 263435126 total ops : 292703490 find throughput : 26343512 rq throughput : 0 update throughput : 2926836 query throughput : 26343512 total throughput : 29270349 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=46.0698 PAPI_L3_TCM=8.59862 PAPI_TOT_CYC=7417.22 PAPI_TOT_INS=2413.49 garbage=1304255421022420 total_execution_walltime=15.641s REALTIME_MAIN_END_PERF_FORMAT=6993758.315167580 TL2 system shutdown: GCLOCK=0x20C0A1A Starts=346895042 Aborts=4200985 Overflows: R=0 W=0 L=0 [time_cmd_output] time_elapsed_sec=15.87, faults_major=0, faults_minor=695518, mem_maxresident_kb=1562540, user_cputime=1394.37, sys_cputime=86.03, percent_cpu=9326% timeout=false algorithm=brown_sigouin_int_avl_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=1