__step=000482 __cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 60 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" ./ellen_ext_bst_lf.debra -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=data000482.txt __path_data=data/data000482.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6984712.79522946 binary=./ellen_ext_bst_lf.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=ellen_ext_bst_lf 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=40 descriptor=64 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 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.004s prefilling_size=999391 prefilling_elapsed_millis=1.001s finished prefilling to size 999392 for expected size 1000000 keysum=999483174306, performing 26741822 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=999392 pref_millis=1102 prefill_elapsed_ms=1129 6node_tIxPvE_object_size=40 6node_tIxPvE_allocated_count=0 6node_tIxPvE_allocated_size=0MB 6node_tIxPvE_get_from_pool=0 6node_tIxPvE_deallocated=0 6node_tIxPvE_limbo_count=0 6node_tIxPvE_limbo_details=0 0 0 global_epoch_counter=1296 6info_tIxPvE_object_size=64 6info_tIxPvE_allocated_count=0 6info_tIxPvE_allocated_size=0MB 6info_tIxPvE_get_from_pool=0 6info_tIxPvE_deallocated=0 6info_tIxPvE_limbo_count=0 6info_tIxPvE_limbo_details=0 0 0 global_epoch_counter=0 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6984713.350176355 REALTIME_END_PERF_FORMAT=6984723.350318287 ############################################################################### ################################## 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 924 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.033s tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 64 126 245 462 857 1562 2760 4744 7851 12531 18976 27551 37997 49932 62436 73827 82463 87343 87477 83470 76378 66335 55454 44860 34923 25838 18313 12513 8430 5526 3499 2072 1180 617 332 160 68 21 11 6 0 tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 0 2 7 28 67 152 364 776 1637 3171 6086 10401 17105 26062 37428 51045 65191 77583 87209 91484 90562 86421 77216 66048 54797 44008 33363 24113 16596 11334 7553 4926 2964 1743 902 504 252 115 31 16 12 tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 128 252 490 924 1714 3124 5520 9488 15702 25062 37952 55102 75994 99864 124872 147654 164926 174686 174954 166940 152756 132670 110908 89720 69846 51676 36626 25026 16860 11052 6998 4144 2360 1234 664 320 136 42 22 12 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 2 7 28 67 152 364 776 1637 3171 6086 10401 17105 26062 37428 51045 65191 77583 87209 91484 90562 86421 77216 66048 54797 44008 33363 24113 16596 11334 7553 4926 2964 1743 902 504 252 115 31 16 12 tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 2 1.98438 1.97222 1.94286 1.92749 1.91132 1.88348 1.85942 1.82747 1.79805 1.75716 1.72594 1.68958 1.65705 1.62521 1.59122 1.55849 1.52959 1.50077 1.4771 1.45752 1.43425 1.41798 1.40448 1.38924 1.36993 1.35438 1.34164 1.33685 1.32776 1.31659 1.29608 1.28475 1.26144 1.26904 1.24096 1.2125 1.15441 1.2619 1.27273 1 tree_stats_height=49 tree_stats_numInternals=999275 tree_stats_numLeaves=999276 tree_stats_numNodes=1998551 tree_stats_numKeys=999274 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=0.999998 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=27.9105 tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 5120 10080 19600 36960 68560 124960 220800 379520 628080 1002480 1518080 2204080 3039760 3994560 4994880 5906160 6597040 6987440 6998160 6677600 6110240 5306800 4436320 3588800 2793840 2067040 1465040 1001040 674400 442080 279920 165760 94400 49360 26560 12800 5440 1680 880 480 tree_stats_sizeInBytes=79942040 6node_tIxPvE_object_size=40 6node_tIxPvE_allocated_count=0 6node_tIxPvE_allocated_size=0MB 6node_tIxPvE_get_from_pool=0 6node_tIxPvE_deallocated=0 6node_tIxPvE_limbo_count=0 6node_tIxPvE_limbo_details=0 0 0 global_epoch_counter=18295 6info_tIxPvE_object_size=64 6info_tIxPvE_allocated_count=0 6info_tIxPvE_allocated_size=0MB 6info_tIxPvE_get_from_pool=0 6info_tIxPvE_deallocated=0 6info_tIxPvE_limbo_count=0 6info_tIxPvE_limbo_details=0 0 0 global_epoch_counter=0 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:24 8:12131 16:305355 32:162241 64:57 (2^02, 2^03]: 24 (2^03, 2^04]: 12131 (2^04, 2^05]: 305355 (2^05, 2^06]: 162241 (2^06, 2^07]: 57 sum_limbo_reclamation_event_size_total=13747025 count_limbo_reclamation_event_size_by_thread=9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 count_limbo_reclamation_event_size_total=479808 sum_limbo_reclamation_event_count_total=1631904 first_thread_announced_epoch_by_thread=36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 36590 sum_num_inserts_by_thread=385887 388338 391167 390772 389627 385955 387534 389600 393531 394425 389023 387945 388080 389467 393253 392561 391119 388310 386252 388683 391894 393264 389104 383982 385351 390663 392282 391843 389420 386763 388150 390060 393608 393918 388086 386677 388113 389859 393952 393973 390173 386098 385325 387209 391882 393228 387121 386055 sum_num_inserts_total=18699582 sum_num_deletes_by_thread=385940 388346 391442 391533 389079 385950 388267 389914 393097 394781 389306 387029 388847 390430 393808 393240 391541 389540 386731 388864 391151 391931 388641 385513 386423 389018 392348 392130 389692 385670 387802 390477 394839 395152 390491 387538 388011 389792 393338 394336 389371 388480 385554 387904 390928 392552 388731 385503 sum_num_deletes_total=18711001 sum_num_searches_by_thread=6942529 6998841 7051131 7042880 7015921 6945229 6975435 7023309 7080554 7104834 7007809 6965778 6992045 7035113 7074931 7075000 7031250 6998483 6947701 6997391 7052837 7065476 6993127 6927397 6949634 7005109 7049315 7050565 7026856 6951016 6976456 7013834 7093859 7100107 7004424 6965365 6986462 7032143 7095363 7088264 7022112 6980282 6925194 6983766 7042396 7061682 6983928 6933217 sum_num_searches_total=336666350 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=7714356 7775525 7833740 7825185 7794627 7717134 7751236 7802823 7867182 7894040 7786138 7740752 7768972 7815010 7861992 7860801 7813910 7776333 7720684 7774938 7835882 7850671 7770872 7696892 7721408 7784790 7833945 7834538 7805968 7723449 7752408 7794371 7882306 7889177 7783001 7739580 7762586 7811794 7882653 7876573 7801656 7754860 7696073 7758879 7825206 7847462 7759780 7704775 average_num_operations_total=7793269 stdev_num_operations_total=8061 sum_num_operations_total=374076933 min_num_operations_total=7696073 max_num_operations_total=7894040 first_time_thread_terminate_by_thread=10000141 10000140 10000140 10000141 10000141 10000141 10000141 10000141 10000141 10000140 10000141 10000141 10000141 10000141 10000141 10000141 10000140 10000141 10000140 10000141 10000141 10000141 10000141 10000141 10000141 10000140 10000140 10000141 10000141 10000141 10000141 10000141 10000141 10000140 10000141 10000141 10000141 10000141 10000141 10000141 10000140 10000141 10000140 10000141 10000141 10000141 10000141 10000141 min_time_thread_terminate_total=10000140 max_time_thread_terminate_total=10000141 first_time_thread_start_by_thread=5 5 6 5 5 6 6 5 5 5 6 6 5 6 6 5 6 6 6 6 5 6 5 6 5 5 6 5 5 6 6 5 5 5 6 6 5 6 6 5 6 6 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=998947891591 threads_final_size=999274 final_keysum=998947891591 final_size=999274 validate_result=success Validation OK. Structural validation OK. total_find=336666350 total_rq=0 total_inserts=18699582 total_deletes=18711001 total_updates=37410583 total_queries=336666350 total_ops=374076933 find_throughput=33666635 rq_throughput=0 update_throughput=3741058 query_throughput=33666635 total_throughput=37407693 total find : 336666350 total rq : 0 total inserts : 18699582 total deletes : 18711001 total updates : 37410583 total queries : 336666350 total ops : 374076933 find throughput : 33666635 rq throughput : 0 update throughput : 3741058 query throughput : 33666635 total throughput : 37407693 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=22.0015 PAPI_L3_TCM=4.5477 PAPI_TOT_CYC=3575.78 PAPI_TOT_INS=375.599 garbage=168249124952121 total_execution_walltime=11.409s REALTIME_MAIN_END_PERF_FORMAT=6984723.489807209 [time_cmd_output] time_elapsed_sec=11.54, faults_major=0, faults_minor=100295, mem_maxresident_kb=401960, user_cputime=561.80, sys_cputime=3.06, percent_cpu=4891% timeout=false algorithm=ellen_ext_bst_lf.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=2