__step=000068 __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=data000068.txt __path_data=data/data000068.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6993758.600522579 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.016s prefilling_size=4230085 prefilling_elapsed_millis=1.001s prefilling_size=4541205 prefilling_elapsed_millis=1.101s prefilling_size=4832728 prefilling_elapsed_millis=1.201s prefilling_size=5110492 prefilling_elapsed_millis=1.301s prefilling_size=5374568 prefilling_elapsed_millis=1.402s prefilling_size=5624661 prefilling_elapsed_millis=1.502s prefilling_size=5860403 prefilling_elapsed_millis=1.602s prefilling_size=6082846 prefilling_elapsed_millis=1.702s prefilling_size=6293434 prefilling_elapsed_millis=1.802s prefilling_size=6493567 prefilling_elapsed_millis=1.903s prefilling_size=6684264 prefilling_elapsed_millis=2.003s prefilling_size=6862954 prefilling_elapsed_millis=2.103s prefilling_size=7032489 prefilling_elapsed_millis=2.203s prefilling_size=7192362 prefilling_elapsed_millis=2.303s prefilling_size=7344169 prefilling_elapsed_millis=2.404s prefilling_size=7487529 prefilling_elapsed_millis=2.504s prefilling_size=7624430 prefilling_elapsed_millis=2.604s prefilling_size=7753041 prefilling_elapsed_millis=2.704s prefilling_size=7873286 prefilling_elapsed_millis=2.804s prefilling_size=7987504 prefilling_elapsed_millis=2.905s prefilling_size=8096121 prefilling_elapsed_millis=3.005s prefilling_size=8198311 prefilling_elapsed_millis=3.105s prefilling_size=8296301 prefilling_elapsed_millis=3.205s prefilling_size=8387321 prefilling_elapsed_millis=3.305s prefilling_size=8474141 prefilling_elapsed_millis=3.406s prefilling_size=8556393 prefilling_elapsed_millis=3.506s prefilling_size=8633204 prefilling_elapsed_millis=3.606s prefilling_size=8707740 prefilling_elapsed_millis=3.706s prefilling_size=8778870 prefilling_elapsed_millis=3.806s prefilling_size=8844498 prefilling_elapsed_millis=3.907s prefilling_size=8905412 prefilling_elapsed_millis=4.007s prefilling_size=8965508 prefilling_elapsed_millis=4.107s prefilling_size=9020607 prefilling_elapsed_millis=4.207s prefilling_size=9073384 prefilling_elapsed_millis=4.307s prefilling_size=9123749 prefilling_elapsed_millis=4.408s prefilling_size=9171538 prefilling_elapsed_millis=4.508s prefilling_size=9215976 prefilling_elapsed_millis=4.608s prefilling_size=9257630 prefilling_elapsed_millis=4.708s prefilling_size=9298152 prefilling_elapsed_millis=4.808s prefilling_size=9336803 prefilling_elapsed_millis=4.909s prefilling_size=9372240 prefilling_elapsed_millis=5.009s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 627675 keys with 96 threads... tid=0 thread_prefill_amount=89569 percent_done_estimate=1369.9 elapsed_s=5 est_remaining_s=-11 / -6 tid=0 thread_prefill_amount=91652 percent_done_estimate=1401.8 elapsed_s=5 est_remaining_s=-11 / -6 tid=0 thread_prefill_amount=93735 percent_done_estimate=1433.6 elapsed_s=5 est_remaining_s=-11 / -6 timing_elapsed 0.130288s prefilling_size=10000000 prefilling_elapsed_millis=0.13s finished prefilling to size 10000000 for expected size 10000000 keysum=100003066791890, performing 56833143 updates; total_prefilling_elapsed_ms=130 ms) pref_size=10000000 pref_millis=130 prefill_elapsed_ms=130 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6993764.55633906 REALTIME_END_PERF_FORMAT=6993774.55727986 ############################################################################### ################################## 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.028s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262007 518553 959591 1431653 1397524 725192 167090 14423 311 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 132 4880 66521 399654 1141861 1523579 891407 211073 18226 380 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262139 523433 1026112 1831307 2539385 2248771 1058497 225496 18537 380 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262139 523433 1026112 1831307 2539385 2248771 1058497 225496 18537 380 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99996 1.99728 1.96967 1.84953 1.60489 1.33522 1.14822 1.05518 1.01824 1.00372 1 tree_stats_height=28 tree_stats_numInternals=5738487 tree_stats_numLeaves=4257713 tree_stats_numNodes=9996200 tree_stats_numKeys=9996200 tree_stats_avgDegreeInternal=1.74196 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42593 tree_stats_avgKeyDepth=21.7042 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=327628 first_thread_announced_epoch_by_thread=8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 8168 sum_num_inserts_by_thread=152731 153477 154864 155367 155259 153138 153074 154324 155355 155847 154797 154135 153485 154383 155504 155837 154730 153345 152310 152612 154443 155473 153448 152979 153814 153988 154788 154772 154672 152250 153262 153898 156360 156117 154404 153861 153296 153957 155709 156033 155598 153801 152665 153240 154562 155839 153844 153255 154919 156232 157732 158253 157375 156023 156495 156805 158953 158510 158378 155483 156091 156408 158636 158317 157599 155944 154772 156002 158172 158426 157165 156132 155263 156730 158194 158779 156806 157713 156493 157630 158487 158473 157500 156742 156571 157511 157978 157938 157031 155084 155447 156909 158031 158521 157358 157535 sum_num_inserts_total=14948448 sum_num_deletes_by_thread=152265 154050 154859 155025 154352 153137 153010 154350 155630 155816 155395 154106 153454 153679 155558 155297 155212 154024 152079 154381 154796 155444 154246 153241 152611 154131 154542 155017 153980 152703 153824 154293 156222 155571 154733 153960 153691 154813 154972 155521 155450 153801 152079 153856 154856 154887 154351 152985 155619 156200 157584 157945 157017 157234 155978 156740 158295 159787 157686 156404 156122 157285 158349 158785 157329 155204 155865 156408 158053 157933 157707 156163 155946 156932 158433 158393 157876 156479 156421 155952 158807 159249 158598 157012 156388 156822 157674 158125 157740 155500 154459 156292 157858 159233 157592 156658 sum_num_deletes_total=14952386 sum_num_searches_by_thread=2745254 2761829 2788191 2791033 2786777 2749511 2763671 2778674 2806312 2811069 2785885 2770700 2764192 2778844 2797827 2801042 2787797 2765479 2747909 2765066 2784188 2795760 2775610 2751509 2754111 2767485 2783188 2789796 2785713 2750540 2755675 2768405 2799314 2803751 2784865 2765110 2768106 2782351 2795161 2802285 2795365 2769210 2751868 2767328 2783179 2797733 2779396 2756046 2798313 2815490 2841059 2846154 2822509 2821598 2810489 2820589 2854103 2859713 2843277 2820537 2810173 2832558 2848765 2854228 2834758 2801609 2791553 2815555 2845348 2850174 2835305 2821096 2799930 2820236 2844259 2849663 2831682 2826166 2807570 2823963 2856691 2859245 2843348 2818927 2806321 2826552 2845902 2848121 2837948 2799817 2791097 2817525 2848985 2851044 2834034 2819663 sum_num_searches_total=269113752 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3050250 3069356 3097914 3101425 3096388 3055786 3069755 3087348 3117297 3122732 3096077 3078941 3071131 3086906 3108889 3112176 3097739 3072848 3052298 3072059 3093427 3106677 3083304 3057729 3060536 3075604 3092518 3099585 3094365 3055493 3062761 3076596 3111896 3115439 3094002 3072931 3075093 3091121 3105842 3113839 3106413 3076812 3056612 3074424 3092597 3108459 3087591 3062286 3108851 3127922 3156375 3162352 3136901 3134855 3122962 3134134 3171351 3178010 3159341 3132424 3122386 3146251 3165750 3171330 3149686 3112757 3102190 3127965 3161573 3166533 3150177 3133391 3111139 3133898 3160886 3166835 3146364 3140358 3120484 3137545 3173985 3176967 3159446 3132681 3119280 3140885 3161554 3164184 3152719 3110401 3101003 3130726 3164874 3168798 3148984 3133856 average_num_operations_total=3114735 stdev_num_operations_total=3642 sum_num_operations_total=299014586 min_num_operations_total=3050250 max_num_operations_total=3178010 first_time_thread_terminate_by_thread=10000170 10000095 10000096 10000094 10000096 10000096 10000094 10000095 10000093 10000093 10000094 10000093 10000095 10000095 10000094 10000096 10000094 10000095 10000095 10000094 10000097 10000094 10000095 10000094 10000097 10000095 10000096 10000094 10000096 10000096 10000094 10000098 10000094 10000093 10000098 10000093 10000095 10000098 10000094 10000096 10000095 10000095 10000095 10000095 10000097 10000094 10000095 10000094 10000097 10000096 10000096 10000096 10000094 10000095 10000094 10000096 10000096 10000095 10000095 10000097 10000095 10000096 10000101 10000097 10000096 10000094 10000094 10000096 10000094 10000094 10000095 10000094 10000097 10000094 10000097 10000094 10000094 10000096 10000094 10000097 10000096 10000095 10000094 10000094 10000094 10000096 10000096 10000094 10000094 10000095 10000094 10000096 10000094 10000094 10000097 10000095 min_time_thread_terminate_total=10000093 max_time_thread_terminate_total=10000170 first_time_thread_start_by_thread=10 6 5 6 5 6 5 6 5 5 5 5 6 5 6 5 5 6 6 5 5 5 5 5 4 6 5 6 5 6 5 6 5 5 5 5 6 5 6 5 5 6 6 5 5 5 5 5 6 6 5 5 6 5 5 6 6 6 6 5 6 6 5 6 5 5 6 6 5 5 5 5 6 6 5 5 6 5 5 6 6 6 6 5 6 6 5 6 6 5 6 6 5 5 5 5 min_time_thread_start_total=4 max_time_thread_start_total=10 sum_duration_all_ops_total= threads_final_keysum=99967981879603 threads_final_size=9996200 final_keysum=99967981879603 final_size=9996200 validate_result=success Validation OK. Structural validation OK. total_find=269113752 total_rq=0 total_inserts=14948448 total_deletes=14952386 total_updates=29900834 total_queries=269113752 total_ops=299014586 find_throughput=26911375 rq_throughput=0 update_throughput=2990083 query_throughput=26911375 total_throughput=29901458 total find : 269113752 total rq : 0 total inserts : 14948448 total deletes : 14952386 total updates : 29900834 total queries : 269113752 total ops : 299014586 find throughput : 26911375 rq throughput : 0 update throughput : 2990083 query throughput : 26911375 total throughput : 29901458 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=46.6328 PAPI_L3_TCM=8.65124 PAPI_TOT_CYC=7245.22 PAPI_TOT_INS=2417.76 garbage=1345656477639942 total_execution_walltime=15.609s REALTIME_MAIN_END_PERF_FORMAT=6993774.210517142 TL2 system shutdown: GCLOCK=0x24A9592 Starts=359676513 Aborts=5031744 Overflows: R=0 W=0 L=0 [time_cmd_output] time_elapsed_sec=15.84, faults_major=0, faults_minor=730394, mem_maxresident_kb=1633588, user_cputime=1383.41, sys_cputime=89.46, percent_cpu=9297% 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=2