__step=000244 __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 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000244.txt __path_data=data/data000244.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6997494.172514336 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=2000000 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.027s prefilling_size=995539 prefilling_elapsed_millis=1.001s finished prefilling to size 995548 for expected size 1000000 keysum=996056518586, performing 11992092 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=995548 pref_millis=1102 prefill_elapsed_ms=1128 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=141 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6997495.508520916 REALTIME_END_PERF_FORMAT=6997505.508615409 ############################################################################### ################################## 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 900 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 127 242 461 864 1572 2726 4560 7253 11066 16055 22041 28985 36106 43031 49074 53559 55532 54927 51667 46649 40433 33770 27100 20695 15143 10627 7263 4676 2974 1817 1019 559 252 96 36 15 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 9 14 36 86 230 500 1014 1901 3323 5469 8193 11802 15683 19770 23936 27509 29656 30757 30100 27968 24770 21178 17620 13569 10168 7336 5158 3359 2133 1357 766 444 211 74 24 15 1 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 127 251 475 900 1658 2956 5060 8267 12967 19378 27510 37178 47908 58714 68844 77495 83041 84583 82424 76749 68401 58540 48278 38315 28712 20795 14599 9834 6333 3950 2376 1325 696 307 110 39 16 1 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 127 251 475 900 1658 2956 5060 8267 12967 19378 27510 37178 47908 58714 68844 77495 83041 84583 82424 76749 68401 58540 48278 38315 28712 20795 14599 9834 6333 3950 2376 1325 696 307 110 39 16 1 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.98438 1.97638 1.92829 1.92421 1.88222 1.83474 1.78958 1.73261 1.69118 1.64101 1.59113 1.55024 1.50898 1.4719 1.43964 1.41283 1.38044 1.34984 1.32509 1.3043 1.28342 1.26472 1.24783 1.2323 1.20924 1.19685 1.19101 1.17611 1.1685 1.15411 1.14152 1.12879 1.1034 1.07902 1.0456 1.02727 1.02564 1 1 tree_stats_height=45 tree_stats_numInternals=653100 tree_stats_numLeaves=346139 tree_stats_numNodes=999239 tree_stats_numKeys=999239 tree_stats_avgDegreeInternal=1.52999 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.3464 tree_stats_avgKeyDepth=24.0985 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=4709 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:1 4:55 8:24416 16:399267 32:14502 64:47 128:48 (2^01, 2^02]: 1 (2^02, 2^03]: 55 (2^03, 2^04]: 24416 (2^04, 2^05]: 399267 (2^05, 2^06]: 14502 (2^06, 2^07]: 47 (2^07, 2^08]: 48 sum_limbo_reclamation_event_size_total=9929399 count_limbo_reclamation_event_size_by_thread=4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 4566 count_limbo_reclamation_event_size_total=438336 sum_limbo_reclamation_event_count_total=438528 first_thread_announced_epoch_by_thread=9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 9418 sum_num_inserts_by_thread=199411 201381 201275 203991 202786 202002 200473 202314 203373 204639 201847 202289 201848 202250 201533 202562 202833 201481 200292 200876 201986 203513 201977 199178 200538 200265 203068 202563 202478 199559 201629 202360 202927 204091 202823 201482 200952 203350 202382 203044 201917 202163 199250 201241 202565 202889 201379 199981 209499 211067 212682 211989 212462 211627 209988 210586 213238 214181 213765 212394 210138 212203 212675 214328 212485 210243 210386 211758 212975 213125 212917 211152 211199 212827 213305 215012 212726 211076 211107 211518 213034 213637 211862 211237 211654 212591 212195 213748 211961 210529 211072 212124 215071 214972 212101 211280 sum_num_inserts_total=19876707 sum_num_deletes_by_thread=199796 200363 202256 202779 202198 201251 200212 201593 203337 204458 202356 201467 202591 202654 201575 203003 203053 202241 200377 201025 202260 203660 201014 200150 199560 200251 201916 201872 202967 199111 201024 202219 203273 204352 201819 201662 200728 202080 202298 202553 202106 202292 199967 201640 201861 203478 202422 199621 210325 210152 212691 212773 211328 211641 210089 211326 212300 214774 213322 211349 211029 212423 212791 214197 212544 210725 209919 211318 212726 214042 214640 209899 211033 212686 213636 215253 212181 211959 211304 212918 213336 214176 211904 210826 211193 212328 213513 214163 212487 211229 211679 210949 213647 216008 212455 212441 sum_num_deletes_total=19878368 sum_num_searches_by_thread=3592877 3613347 3636326 3658702 3646387 3619504 3605387 3634782 3662498 3678365 3633212 3626413 3633844 3639605 3639378 3650610 3660100 3626637 3607061 3609868 3639096 3668365 3628536 3592525 3596021 3607248 3649628 3637088 3640962 3592511 3622293 3636964 3652575 3671739 3644438 3626791 3621787 3645498 3644505 3652853 3635385 3631559 3591828 3632026 3641276 3650593 3639830 3595555 3769254 3791144 3820369 3825523 3808375 3805581 3786548 3797258 3842115 3854337 3845996 3810732 3787946 3821070 3838918 3846668 3816464 3793321 3788425 3799017 3833091 3842616 3843304 3797001 3799719 3823922 3833795 3859081 3830689 3814209 3806664 3822218 3842944 3854284 3811435 3795100 3803070 3824800 3837610 3842894 3824040 3793626 3797942 3815775 3851020 3867872 3825749 3810832 sum_num_searches_total=357718741 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3992084 4015091 4039857 4065472 4051371 4022757 4006072 4038689 4069208 4087462 4037415 4030169 4038283 4044509 4042486 4056175 4065986 4030359 4007730 4011769 4043342 4075538 4031527 3991853 3996119 4007764 4054612 4041523 4046407 3991181 4024946 4041543 4058775 4080182 4049080 4029935 4023467 4050928 4049185 4058450 4039408 4036014 3991045 4034907 4045702 4056960 4043631 3995157 4189078 4212363 4245742 4250285 4232165 4228849 4206625 4219170 4267653 4283292 4273083 4234475 4209113 4245696 4264384 4275193 4241493 4214289 4208730 4222093 4258792 4269783 4270861 4218052 4221951 4249435 4260736 4289346 4255596 4237244 4229075 4246654 4269314 4282097 4235201 4217163 4225917 4249719 4263318 4270805 4248488 4215384 4220693 4238848 4279738 4298852 4250305 4234553 average_num_operations_total=4140352 stdev_num_operations_total=11028 sum_num_operations_total=397473816 min_num_operations_total=3991045 max_num_operations_total=4298852 first_time_thread_terminate_by_thread=10000095 10000096 10000095 10000094 10000096 10000094 10000094 10000097 10000095 10000096 10000094 10000096 10000096 10000095 10000096 10000094 10000096 10000094 10000095 10000096 10000096 10000097 10000094 10000096 10000095 10000094 10000095 10000094 10000096 10000094 10000094 10000097 10000094 10000097 10000096 10000094 10000096 10000094 10000096 10000096 10000096 10000094 10000094 10000095 10000096 10000095 10000094 10000096 10000094 10000097 10000096 10000097 10000094 10000094 10000095 10000094 10000097 10000094 10000096 10000094 10000094 10000095 10000097 10000095 10000095 10000094 10000096 10000097 10000097 10000097 10000095 10000096 10000094 10000097 10000096 10000097 10000094 10000094 10000094 10000097 10000097 10000094 10000096 10000094 10000096 10000095 10000097 10000095 10000095 10000094 10000097 10000097 10000095 10000097 10000095 10000096 min_time_thread_terminate_total=10000094 max_time_thread_terminate_total=10000097 first_time_thread_start_by_thread=5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 min_time_thread_start_total=4 max_time_thread_start_total=5 sum_duration_all_ops_total= threads_final_keysum=998961212191 threads_final_size=999239 final_keysum=998961212191 final_size=999239 validate_result=success Validation OK. Structural validation OK. total_find=357718741 total_rq=0 total_inserts=19876707 total_deletes=19878368 total_updates=39755075 total_queries=357718741 total_ops=397473816 find_throughput=35771874 rq_throughput=0 update_throughput=3975507 query_throughput=35771874 total_throughput=39747381 total find : 357718741 total rq : 0 total inserts : 19876707 total deletes : 19878368 total updates : 39755075 total queries : 357718741 total ops : 397473816 find throughput : 35771874 rq throughput : 0 update throughput : 3975507 query throughput : 35771874 total throughput : 39747381 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=22.1536 PAPI_L3_TCM=0.906546 PAPI_TOT_CYC=4839.72 PAPI_TOT_INS=2403.75 garbage=173484651821349 total_execution_walltime=11.415s REALTIME_MAIN_END_PERF_FORMAT=6997505.609219272 TL2 system shutdown: GCLOCK=0x183F39C Starts=410771846 Aborts=1305937 Overflows: R=0 W=0 L=0 [time_cmd_output] time_elapsed_sec=11.57, faults_major=0, faults_minor=99033, mem_maxresident_kb=355980, user_cputime=1009.93, sys_cputime=82.93, percent_cpu=9440% 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