__step=000873 __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" ./bronson_pext_bst_occ.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000873.txt __path_data=data/data000873.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6989487.948020983 binary=./bronson_pext_bst_occ.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=bronson_pext_bst_occ 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=50 DEL_FRAC=50 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=50.0 50.0 PREFILL_TYPE=PREFILL_HYBRID PREFILL_HYBRID_MIN_MS=1000 PREFILL_HYBRID_MAX_MS=5000 sizes: node=56 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.006s prefilling_size=1000201 prefilling_elapsed_millis=1.001s finished prefilling to size 1000198 for expected size 1000000 keysum=1000176712271, performing 42821278 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=1000198 pref_millis=1102 prefill_elapsed_ms=1120 6node_tIxPvE_object_size=56 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=1985 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6989489.246350535 REALTIME_END_PERF_FORMAT=6989499.246507901 ############################################################################### ################################## 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 1024 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.036s tree_stats_numInternalsAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65391 125645 192977 169778 60888 5890 79 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 136 4691 49953 172380 206645 79433 7466 99 tree_stats_numNodesAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65527 130336 242930 342158 267533 85323 7545 99 tree_stats_numKeysAtDepth=0 0 2 1 4 9 14 39 74 120 271 489 1032 2068 4064 8171 16751 34984 80836 187914 310348 260762 84940 7542 99 tree_stats_avgDegreeAtDepth=1 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99973 1.99112 1.89987 1.61409 1.28569 1.0913 1.01939 1.00265 1 tree_stats_height=25 tree_stats_numInternals=686184 tree_stats_numLeaves=520803 tree_stats_numNodes=1206987 tree_stats_numKeys=1000534 tree_stats_avgDegreeInternal=1.75898 tree_stats_avgDegreeLeaves=0.999969 tree_stats_avgDegree=1.43148 tree_stats_avgKeyDepth=19.834 tree_stats_bytesAtDepth=56 56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3669512 7298816 13604080 19160848 14981848 4778088 422520 5544 tree_stats_sizeInBytes=67591272 6node_tIxPvE_object_size=56 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=20159 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:3 16:7 32:1673 64:478134 128:87 (2^03, 2^04]: 3 (2^04, 2^05]: 7 (2^05, 2^06]: 1673 (2^06, 2^07]: 478134 (2^07, 2^08]: 87 sum_limbo_reclamation_event_size_total=42789610 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 count_limbo_reclamation_event_size_total=479904 sum_limbo_reclamation_event_count_total=872352 first_thread_announced_epoch_by_thread=40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 40318 sum_num_inserts_by_thread=4033604 4058084 4090195 4167119 4069564 4030013 4116534 4136699 4113617 4184937 4133373 4042222 4117664 4142191 4168776 4176418 4156976 4128611 4024359 4114164 4058173 4069319 4119836 4025094 4113839 4129731 4154666 4138621 4096707 4061845 4042347 4141196 4116391 4108081 4052822 4013874 4098141 4037009 4167222 4177778 4154837 4051651 4083838 4028214 4129445 4076239 4120940 4023951 sum_num_inserts_total=196796927 sum_num_deletes_by_thread=4035576 4056298 4091225 4167327 4071619 4023524 4120079 4134810 4108336 4183694 4136270 4041394 4114563 4143234 4169005 4177000 4155747 4127914 4026573 4108152 4055832 4073026 4112766 4024241 4112249 4134798 4152166 4138105 4099611 4062125 4043923 4133130 4110123 4106085 4051057 4012863 4094962 4040298 4169100 4174925 4155357 4048660 4081510 4027878 4131967 4080250 4120082 4022846 sum_num_deletes_total=196762275 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=8069180 8114382 8181420 8334446 8141183 8053537 8236613 8271509 8221953 8368631 8269643 8083616 8232227 8285425 8337781 8353418 8312723 8256525 8050932 8222316 8114005 8142345 8232602 8049335 8226088 8264529 8306832 8276726 8196318 8123970 8086270 8274326 8226514 8214166 8103879 8026737 8193103 8077307 8336322 8352703 8310194 8100311 8165348 8056092 8261412 8156489 8241022 8046797 average_num_operations_total=8199150 stdev_num_operations_total=14490 sum_num_operations_total=393559202 min_num_operations_total=8026737 max_num_operations_total=8368631 first_time_thread_terminate_by_thread=10000158 10000157 10000157 10000157 10000157 10000157 10000158 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 10000157 min_time_thread_terminate_total=10000157 max_time_thread_terminate_total=10000158 first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 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=3 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=1000052257978 threads_final_size=1000534 final_keysum=1000052257978 final_size=1000534 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=196796927 total_deletes=196762275 total_updates=393559202 total_queries=0 total_ops=393559202 find_throughput=0 rq_throughput=0 update_throughput=39355920 query_throughput=0 total_throughput=39355920 total find : 0 total rq : 0 total inserts : 196796927 total deletes : 196762275 total updates : 393559202 total queries : 0 total ops : 393559202 find throughput : 0 rq throughput : 0 update throughput : 39355920 query throughput : 0 total throughput : 39355920 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=20.9453 PAPI_L3_TCM=3.82096 PAPI_TOT_CYC=3310.86 PAPI_TOT_INS=1733.47 garbage=0 total_execution_walltime=11.424s REALTIME_MAIN_END_PERF_FORMAT=6989499.372995609 [time_cmd_output] time_elapsed_sec=11.53, faults_major=0, faults_minor=95546, mem_maxresident_kb=383800, user_cputime=558.94, sys_cputime=2.71, percent_cpu=4867% timeout=false algorithm=bronson_pext_bst_occ.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1