__step=000305 __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" ./brown_ext_chromatic_lf.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000305.txt __path_data=data/data000305.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6982447.600134433 binary=./brown_ext_chromatic_lf.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_ext_chromatic_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=0.5 DEL_FRAC=0.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=0.5 0.5 PREFILL_TYPE=PREFILL_HYBRID PREFILL_HYBRID_MIN_MS=1000 PREFILL_HYBRID_MAX_MS=5000 sizes: node=56 descriptor=128 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.005s prefilling_size=99556 prefilling_elapsed_millis=1.001s finished prefilling to size 99560 for expected size 100000 keysum=9955173009, performing 28620904 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=99560 pref_millis=1102 prefill_elapsed_ms=1123 4NodeIxPvE_object_size=56 4NodeIxPvE_allocated_count=0 4NodeIxPvE_allocated_size=0MB 4NodeIxPvE_get_from_pool=0 4NodeIxPvE_deallocated=0 4NodeIxPvE_limbo_count=0 4NodeIxPvE_limbo_details=0 0 0 global_epoch_counter=2134 9SCXRecordIxPvE_object_size=128 9SCXRecordIxPvE_allocated_count=0 9SCXRecordIxPvE_allocated_size=0MB 9SCXRecordIxPvE_get_from_pool=0 9SCXRecordIxPvE_deallocated=0 9SCXRecordIxPvE_limbo_count=0 9SCXRecordIxPvE_limbo_details=0 0 0 global_epoch_counter=0 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6982448.894403164 REALTIME_END_PERF_FORMAT=6982458.894557630 ############################################################################### ################################## 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 2 4 8 16 32 64 128 256 512 1024 2048 4067 7853 13810 19994 21606 16435 8525 2957 703 108 11 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 29 281 1896 7626 18382 26777 24345 14093 5211 1298 205 21 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8134 15706 27620 39988 43212 32870 17050 5914 1406 216 22 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 29 281 1896 7626 18382 26777 24345 14093 5211 1298 205 21 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 1.99292 1.96545 1.87928 1.7239 1.54031 1.38033 1.25936 1.17343 1.11887 1.07681 1.05093 1.04545 1 tree_stats_height=25 tree_stats_numInternals=100165 tree_stats_numLeaves=100166 tree_stats_numNodes=200331 tree_stats_numKeys=100166 tree_stats_avgDegreeInternal=2.00001 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=17.3387 tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 455504 879536 1546720 2239328 2419872 1840720 954800 331184 78736 12096 1232 112 tree_stats_sizeInBytes=11218536 4NodeIxPvE_object_size=56 4NodeIxPvE_allocated_count=0 4NodeIxPvE_allocated_size=0MB 4NodeIxPvE_get_from_pool=0 4NodeIxPvE_deallocated=0 4NodeIxPvE_limbo_count=0 4NodeIxPvE_limbo_details=0 0 0 global_epoch_counter=45655 9SCXRecordIxPvE_object_size=128 9SCXRecordIxPvE_allocated_count=0 9SCXRecordIxPvE_allocated_size=0MB 9SCXRecordIxPvE_get_from_pool=0 9SCXRecordIxPvE_deallocated=0 9SCXRecordIxPvE_limbo_count=0 9SCXRecordIxPvE_limbo_details=0 0 0 global_epoch_counter=0 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:81730 2:136747 4:148592 8:45497 16:1260 [2^00, 2^01]: 81730 (2^01, 2^02]: 136747 (2^02, 2^03]: 148592 (2^03, 2^04]: 45497 (2^04, 2^05]: 1260 sum_limbo_reclamation_event_size_total=1659746 count_limbo_reclamation_event_size_by_thread=8588 8628 8665 8591 8618 8595 8557 8579 8676 8662 8680 8546 8625 8585 8691 8633 8691 8630 8556 8621 8621 8590 8641 8556 8521 8562 8621 8637 8613 8612 8691 8615 8627 8666 8597 8618 8640 8609 8735 8664 8690 8605 8572 8663 8644 8645 8603 8551 count_limbo_reclamation_event_size_total=413826 sum_limbo_reclamation_event_count_total=4178016 first_thread_announced_epoch_by_thread=91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 91310 sum_num_inserts_by_thread=99754 100361 101309 100396 100746 98628 100014 100925 102237 102264 100623 99857 99888 100523 101657 101921 101565 100672 99314 100805 101715 100616 99511 99914 100241 100213 101943 102266 100848 99346 100171 100708 101828 102330 100344 100462 100738 100915 101640 101543 100564 100312 98854 100128 101435 101173 100081 100241 sum_num_inserts_total=4833539 sum_num_deletes_by_thread=98973 100831 101930 100696 101333 99276 99527 101317 102411 101574 100361 100044 100173 100228 101447 101270 101519 101041 100169 100319 101403 100604 99901 99383 99882 100461 101249 101337 101215 99995 100338 100165 102181 102644 100540 100331 100558 100748 101955 101367 100813 100309 98965 100398 101329 100777 100326 99559 sum_num_deletes_total=4833172 sum_num_searches_by_thread=19767900 19890382 20053677 20005954 19914127 19596542 19799724 19917546 20165577 20250674 19876898 19889148 19852508 19926975 20119492 20087329 20082141 19924684 19775328 19971943 20058882 19985010 19733718 19685883 19782693 19919487 20111553 20150535 20007342 19714980 19890332 19911482 20167227 20270162 19999852 19923066 19945675 19927258 20144240 20094414 19967128 19847766 19635354 19834832 20083846 19999941 19915518 19694983 sum_num_searches_total=957271708 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=19966627 20091574 20256916 20207046 20116206 19794446 19999265 20119788 20370225 20454512 20077882 20089049 20052569 20127726 20322596 20290520 20285225 20126397 19974811 20173067 20262000 20186230 19933130 19885180 19982816 20120161 20314745 20354138 20209405 19914321 20090841 20112355 20371236 20475136 20200736 20123859 20146971 20128921 20347835 20297324 20168505 20048387 19833173 20035358 20286610 20201891 20115925 19894783 average_num_operations_total=20144550 stdev_num_operations_total=23160 sum_num_operations_total=966938419 min_num_operations_total=19794446 max_num_operations_total=20475136 first_time_thread_terminate_by_thread=10000154 10000154 10000154 10000153 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000153 10000154 10000154 10000154 10000154 10000154 10000154 10000153 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000154 10000153 10000154 10000154 10000154 min_time_thread_terminate_total=10000153 max_time_thread_terminate_total=10000154 first_time_thread_start_by_thread=4 4 4 4 3 4 3 3 3 4 3 4 4 4 4 3 4 4 3 4 4 4 4 4 4 4 4 4 3 4 3 3 3 4 3 4 4 4 4 3 4 4 3 4 4 4 4 4 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=9997580356 threads_final_size=100166 final_keysum=9997580356 final_size=100166 validate_result=success Validation OK. Structural validation OK. total_find=957271708 total_rq=0 total_inserts=4833539 total_deletes=4833172 total_updates=9666711 total_queries=957271708 total_ops=966938419 find_throughput=95727170 rq_throughput=0 update_throughput=966671 query_throughput=95727170 total_throughput=96693841 total find : 957271708 total rq : 0 total inserts : 4833539 total deletes : 4833172 total updates : 9666711 total queries : 957271708 total ops : 966938419 find throughput : 95727170 rq throughput : 0 update throughput : 966671 query throughput : 95727170 total throughput : 96693841 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=7.46115 PAPI_L3_TCM=0.00566029 PAPI_TOT_CYC=1378.04 PAPI_TOT_INS=310.003 garbage=47857366551773 total_execution_walltime=11.457s REALTIME_MAIN_END_PERF_FORMAT=6982459.57819409 [time_cmd_output] time_elapsed_sec=11.59, faults_major=0, faults_minor=82208, mem_maxresident_kb=330068, user_cputime=561.03, sys_cputime=2.21, percent_cpu=4858% timeout=false algorithm=brown_ext_chromatic_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