__step=000593 __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 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000593.txt __path_data=data/data000593.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6986012.816134319 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=200000 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=100506 prefilling_elapsed_millis=1.001s finished prefilling to size 100558 for expected size 100000 keysum=10050923781, performing 49933878 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100558 pref_millis=1102 prefill_elapsed_ms=1123 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=2703 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=6986014.113772674 REALTIME_END_PERF_FORMAT=6986024.113934494 ############################################################################### ################################## 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 1148 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.059s tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 55 92 161 304 574 1032 1743 2739 4091 5630 7363 8857 10034 10667 10595 9664 8164 6164 4327 2988 1982 1264 758 396 175 69 20 12 4 1 1 0 tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 9 18 23 18 34 116 321 747 1387 2552 3897 5869 7680 9401 10739 11526 11164 10164 8001 5666 3994 2700 1770 1120 617 281 118 28 20 7 1 2 tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 110 184 322 608 1148 2064 3486 5478 8182 11260 14726 17714 20068 21334 21190 19328 16328 12328 8654 5976 3964 2528 1516 792 350 138 40 24 8 2 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9 18 23 18 34 116 321 747 1387 2552 3897 5869 7680 9401 10739 11526 11164 10164 8001 5666 3994 2700 1770 1120 617 281 118 28 20 7 1 2 tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 1.85938 1.83636 1.875 1.9441 1.94408 1.89895 1.84448 1.78571 1.74681 1.6881 1.65391 1.60145 1.56644 1.53154 1.49663 1.45606 1.42239 1.37751 1.35099 1.34527 1.33166 1.31887 1.29984 1.26121 1.22096 1.19714 1.14493 1.3 1.16667 1.125 1.5 1 tree_stats_height=40 tree_stats_numInternals=99991 tree_stats_numLeaves=99992 tree_stats_numNodes=199983 tree_stats_numKeys=99990 tree_stats_avgDegreeInternal=2.00001 tree_stats_avgDegreeLeaves=0.99998 tree_stats_avgDegree=1.49999 tree_stats_avgKeyDepth=23.1422 tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 4400 7360 12880 24320 45920 82560 139440 219120 327280 450400 589040 708560 802720 853360 847600 773120 653120 493120 346160 239040 158560 101120 60640 31680 14000 5520 1600 960 320 80 80 tree_stats_sizeInBytes=7999320 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=43340 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:1 4:118 8:11560 16:303146 32:164908 64:75 (2^01, 2^02]: 1 (2^02, 2^03]: 118 (2^03, 2^04]: 11560 (2^04, 2^05]: 303146 (2^05, 2^06]: 164908 (2^06, 2^07]: 75 sum_limbo_reclamation_event_size_total=13804619 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=3901142 first_thread_announced_epoch_by_thread=86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 86680 sum_num_inserts_by_thread=924587 928289 941781 940092 934304 924799 933516 935209 944820 951167 939413 933525 933104 935391 944723 941899 940545 933666 922531 935846 944992 950384 933995 918151 915870 926514 943035 941563 938412 927815 936541 936485 947886 951164 940230 934097 935306 940108 949903 945890 931987 931073 922122 934395 944388 948912 940204 917123 sum_num_inserts_total=44947752 sum_num_deletes_by_thread=924774 929967 942946 940999 934614 924933 933990 934092 945411 950116 938817 932337 932169 933799 945049 942251 940619 933320 925757 934871 944159 946985 932438 917822 915325 928309 942200 943153 939127 928902 936877 936533 948179 951152 937868 933975 934586 941301 950356 946676 931450 929614 921449 932651 944982 949223 940209 917270 sum_num_deletes_total=44943602 sum_num_searches_by_thread=16628965 16731422 16975539 16937794 16811235 16649053 16811915 16805089 17024396 17116457 16914193 16786769 16789443 16834429 17002561 16970117 16950892 16806520 16637936 16834029 16981752 17067775 16788209 16533264 16467271 16662969 16968042 16973032 16904412 16696066 16855196 16848941 17075462 17118286 16914516 16807071 16833295 16922850 17110144 17049060 16765263 16752195 16589926 16798596 16982946 17073410 16915525 16534260 sum_num_searches_total=809008488 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=18478326 18589678 18860266 18818885 18680153 18498785 18679421 18674390 18914627 19017740 18792423 18652631 18654716 18703619 18892333 18854267 18832056 18673506 18486224 18704746 18870903 18965144 18654642 18369237 18298466 18517792 18853277 18857748 18781951 18552783 18728614 18721959 18971527 19020602 18792614 18675143 18703187 18804259 19010403 18941626 18628700 18612882 18433497 18665642 18872316 18971545 18795938 18368653 average_num_operations_total=18727080 stdev_num_operations_total=26356 sum_num_operations_total=898899842 min_num_operations_total=18298466 max_num_operations_total=19020602 first_time_thread_terminate_by_thread=10000161 10000160 10000161 10000161 10000161 10000160 10000160 10000161 10000160 10000160 10000160 10000161 10000161 10000161 10000160 10000161 10000161 10000161 10000161 10000160 10000160 10000161 10000161 10000161 10000160 10000160 10000161 10000161 10000161 10000160 10000161 10000161 10000160 10000160 10000160 10000161 10000161 10000161 10000160 10000161 10000161 10000161 10000161 10000160 10000160 10000161 10000161 10000161 min_time_thread_terminate_total=10000160 max_time_thread_terminate_total=10000161 first_time_thread_start_by_thread=3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=9995707881 threads_final_size=99990 final_keysum=9995707881 final_size=99990 validate_result=success Validation OK. Structural validation OK. total_find=809008488 total_rq=0 total_inserts=44947752 total_deletes=44943602 total_updates=89891354 total_queries=809008488 total_ops=898899842 find_throughput=80900848 rq_throughput=0 update_throughput=8989135 query_throughput=80900848 total_throughput=89889984 total find : 809008488 total rq : 0 total inserts : 44947752 total deletes : 44943602 total updates : 89891354 total queries : 809008488 total ops : 898899842 find throughput : 80900848 rq throughput : 0 update throughput : 8989135 query throughput : 80900848 total throughput : 89889984 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=10.2127 PAPI_L3_TCM=0.000488113 PAPI_TOT_CYC=1485.95 PAPI_TOT_INS=345.257 garbage=40447761400004 total_execution_walltime=11.45s REALTIME_MAIN_END_PERF_FORMAT=6986024.279298377 [time_cmd_output] time_elapsed_sec=11.58, faults_major=0, faults_minor=78265, mem_maxresident_kb=313064, user_cputime=558.79, sys_cputime=2.45, percent_cpu=4843% 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=1