__step=000538 __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 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=data000538.txt __path_data=data/data000538.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6985368.383055904 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=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=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.007s prefilling_size=998857 prefilling_elapsed_millis=1.001s finished prefilling to size 998754 for expected size 1000000 keysum=999020141775, performing 42559116 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=998754 pref_millis=1102 prefill_elapsed_ms=1131 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=1975 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6985369.670382176 REALTIME_END_PERF_FORMAT=6985379.670516916 ############################################################################### ################################## 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.041s tree_stats_numInternalsAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65425 125626 194190 171464 58645 5351 62 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 110 4794 48732 173790 210368 76421 6778 75 tree_stats_numNodesAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65535 130420 242922 345254 269013 81772 6840 75 tree_stats_numKeysAtDepth=0 1 1 3 4 10 13 31 76 125 244 518 1006 2104 4118 8193 16545 35226 80993 187110 313730 262468 81408 6835 75 tree_stats_avgDegreeAtDepth=1 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99997 1.99176 1.89936 1.62185 1.28252 1.08595 1.0182 1.0019 1 tree_stats_height=25 tree_stats_numInternals=686299 tree_stats_numLeaves=521068 tree_stats_numNodes=1207367 tree_stats_numKeys=1000837 tree_stats_avgDegreeInternal=1.75924 tree_stats_avgDegreeLeaves=0.999967 tree_stats_avgDegree=1.43156 tree_stats_avgKeyDepth=19.8266 tree_stats_bytesAtDepth=56 56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3669960 7303520 13603632 19334224 15064728 4579232 383040 4200 tree_stats_sizeInBytes=67612552 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=24243 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:1221 2:15219 4:151517 8:294823 16:16943 [2^00, 2^01]: 1221 (2^01, 2^02]: 15219 (2^02, 2^03]: 151517 (2^03, 2^04]: 294823 (2^04, 2^05]: 16943 sum_limbo_reclamation_event_size_total=4308439 count_limbo_reclamation_event_size_by_thread=9995 9996 9994 9995 9995 9995 9997 9995 9995 9993 9990 9996 9990 9993 9995 9996 9995 9992 9994 9995 9995 9997 9995 9993 9989 9993 9995 9995 9994 9995 9995 9994 9994 9993 9995 9995 9991 9995 9996 9994 9996 9997 9992 9994 9993 9994 9993 9995 count_limbo_reclamation_event_size_total=479723 sum_limbo_reclamation_event_count_total=1068864 first_thread_announced_epoch_by_thread=48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 48486 sum_num_inserts_by_thread=497406 500416 515673 505997 512422 506268 499292 502356 517177 509241 512211 509491 508065 510294 503433 515767 512821 501608 497238 501131 511249 505721 509828 506343 508441 511154 503958 514664 512929 506545 499739 512558 517577 517602 511025 498023 509274 502721 516162 507908 503958 502430 504932 510473 511143 513561 501323 497686 sum_num_inserts_total=24367234 sum_num_deletes_by_thread=497474 500991 513909 505544 512781 506735 500061 502738 517937 508160 510909 509197 509659 511907 506311 515339 513039 502274 497999 500749 512099 504515 509673 505483 509145 510145 505620 513382 511471 505917 499889 513548 518582 517724 511996 497314 507605 501971 515968 506997 504863 502745 505743 509089 511693 513771 500302 495951 sum_num_deletes_total=24366914 sum_num_searches_by_thread=8959986 9025262 9265692 9098958 9227909 9113320 9000832 9049858 9321178 9165281 9214048 9171701 9166321 9197545 9110720 9296785 9240955 9014306 8954207 9017486 9214514 9099312 9177614 9112091 9149801 9193620 9100079 9262750 9218584 9115998 8988074 9217641 9319572 9332591 9211875 8947152 9149618 9044130 9290688 9123906 9078487 9015498 9113349 9169934 9202689 9261223 9006885 8941895 sum_num_searches_total=438671920 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=9954866 10026669 10295274 10110499 10253112 10126323 10000185 10054952 10356292 10182682 10237168 10190389 10184045 10219746 10120464 10327891 10266815 10018188 9949444 10019366 10237862 10109548 10197115 10123917 10167387 10214919 10109657 10290796 10242984 10128460 9987702 10243747 10355731 10367917 10234896 9942489 10166497 10048822 10322818 10138811 10087308 10020673 10124024 10189496 10225525 10288555 10008510 9935532 average_num_operations_total=10154293 stdev_num_operations_total=17471 sum_num_operations_total=487406068 min_num_operations_total=9935532 max_num_operations_total=10367917 first_time_thread_terminate_by_thread=10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 10000134 min_time_thread_terminate_total=10000134 max_time_thread_terminate_total=10000134 first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 min_time_thread_start_total=4 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=1000750200893 threads_final_size=1000837 final_keysum=1000750200893 final_size=1000837 validate_result=success Validation OK. Structural validation OK. total_find=438671920 total_rq=0 total_inserts=24367234 total_deletes=24366914 total_updates=48734148 total_queries=438671920 total_ops=487406068 find_throughput=43867192 rq_throughput=0 update_throughput=4873414 query_throughput=43867192 total_throughput=48740606 total find : 438671920 total rq : 0 total inserts : 24367234 total deletes : 24366914 total updates : 48734148 total queries : 438671920 total ops : 487406068 find throughput : 43867192 rq throughput : 0 update throughput : 4873414 query throughput : 43867192 total throughput : 48740606 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=17.1344 PAPI_L3_TCM=2.67568 PAPI_TOT_CYC=2694.52 PAPI_TOT_INS=1123.66 garbage=219361271759327 total_execution_walltime=11.44s REALTIME_MAIN_END_PERF_FORMAT=6985379.824192824 [time_cmd_output] time_elapsed_sec=11.56, faults_major=0, faults_minor=94498, mem_maxresident_kb=379816, user_cputime=559.38, sys_cputime=1.62, percent_cpu=4850% 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=2