__step=000985 __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 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000985.txt __path_data=data/data000985.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6990800.98288327 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=200000 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.004s prefilling_size=100098 prefilling_elapsed_millis=1.001s finished prefilling to size 100120 for expected size 100000 keysum=10009260550, performing 64807832 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=100120 pref_millis=1102 prefill_elapsed_ms=1117 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=2989 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6990801.386305092 REALTIME_END_PERF_FORMAT=6990811.386480473 ############################################################################### ################################## 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.034s tree_stats_numInternalsAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8188 15826 22560 12558 1320 4 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 4 509 7935 25438 16567 1629 4 tree_stats_numNodesAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16335 30495 37996 17887 1633 4 tree_stats_numKeysAtDepth=0 1 1 2 1 7 16 30 68 127 260 495 1038 2095 4421 10350 25201 36446 17813 1633 4 tree_stats_avgDegreeAtDepth=1 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99451 1.89801 1.50615 1.1402 1.0175 1 1 tree_stats_height=21 tree_stats_numInternals=68648 tree_stats_numLeaves=52086 tree_stats_numNodes=120734 tree_stats_numKeys=100009 tree_stats_avgDegreeInternal=1.75874 tree_stats_avgDegreeLeaves=0.999942 tree_stats_avgDegree=1.43139 tree_stats_avgKeyDepth=16.4131 tree_stats_bytesAtDepth=56 56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 914760 1707720 2127776 1001672 91448 224 tree_stats_sizeInBytes=6761104 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=30301 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:1 8:0 16:112 32:3113 64:476546 128:132 (2^02, 2^03]: 1 (2^03, 2^04]: 0 (2^04, 2^05]: 112 (2^05, 2^06]: 3113 (2^06, 2^07]: 476546 (2^07, 2^08]: 132 sum_limbo_reclamation_event_size_total=42163281 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=1310976 first_thread_announced_epoch_by_thread=60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 60602 sum_num_inserts_by_thread=6162520 6193679 6272743 6097732 6174540 5962808 5976704 6220775 6305908 6316435 6237725 6190987 6163811 6035251 6066564 6277565 6069366 6002386 6131859 6157624 6222135 6247513 6219227 6166886 6174005 6017801 6185928 6116415 6105245 5870013 6006162 6041646 6301779 6303435 5979005 5865958 5860622 5918155 6270399 6264520 6248869 6017358 6107246 6129627 6047783 6268147 6034183 6172476 sum_num_inserts_total=294179520 sum_num_deletes_by_thread=6157748 6193419 6277604 6096238 6172877 5964271 5984656 6215164 6309804 6322527 6242063 6192891 6166469 6039627 6063535 6280584 6062051 6003410 6133132 6156602 6219241 6243828 6217246 6167302 6173034 6016813 6184182 6115635 6099823 5871085 6001316 6040133 6300165 6305533 5981710 5861802 5860234 5916761 6267384 6267550 6252510 6011815 6104087 6127268 6045603 6267063 6034982 6173263 sum_num_deletes_total=294162040 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=12320268 12387098 12550347 12193970 12347417 11927079 11961360 12435939 12615712 12638962 12479788 12383878 12330280 12074878 12130099 12558149 12131417 12005796 12264991 12314226 12441376 12491341 12436473 12334188 12347039 12034614 12370110 12232050 12205068 11741098 12007478 12081779 12601944 12608968 11960715 11727760 11720856 11834916 12537783 12532070 12501379 12029173 12211333 12256895 12093386 12535210 12069165 12345739 average_num_operations_total=12257115 stdev_num_operations_total=36648 sum_num_operations_total=588341560 min_num_operations_total=11720856 max_num_operations_total=12638962 first_time_thread_terminate_by_thread=10000175 10000175 10000175 10000175 10000174 10000175 10000174 10000175 10000174 10000174 10000174 10000174 10000174 10000174 10000174 10000173 10000174 10000174 10000174 10000174 10000174 10000174 10000175 10000174 10000175 10000175 10000175 10000175 10000174 10000175 10000174 10000175 10000174 10000174 10000174 10000174 10000174 10000174 10000174 10000174 10000174 10000174 10000174 10000174 10000174 10000174 10000175 10000174 min_time_thread_terminate_total=10000173 max_time_thread_terminate_total=10000175 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=10001947536 threads_final_size=100009 final_keysum=10001947536 final_size=100009 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=294179520 total_deletes=294162040 total_updates=588341560 total_queries=0 total_ops=588341560 find_throughput=0 rq_throughput=0 update_throughput=58834156 query_throughput=0 total_throughput=58834156 total find : 0 total rq : 0 total inserts : 294179520 total deletes : 294162040 total updates : 588341560 total queries : 0 total ops : 588341560 find throughput : 0 rq throughput : 0 update throughput : 58834156 query throughput : 0 total throughput : 58834156 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=13.0841 PAPI_L3_TCM=0.000300409 PAPI_TOT_CYC=2213.7 PAPI_TOT_INS=1484.6 garbage=0 total_execution_walltime=11.438s REALTIME_MAIN_END_PERF_FORMAT=6990811.537533473 [time_cmd_output] time_elapsed_sec=11.55, faults_major=0, faults_minor=77211, mem_maxresident_kb=310580, user_cputime=559.60, sys_cputime=4.35, percent_cpu=4878% 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