__step=000865 __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 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=data000865.txt __path_data=data/data000865.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6989393.728964136 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=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 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.021s prefilling_size=1001071 prefilling_elapsed_millis=1.001s finished prefilling to size 1001072 for expected size 1000000 keysum=1001003277905, performing 16177517 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=1001072 pref_millis=1102 prefill_elapsed_ms=1120 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=912 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=6989395.22081050 REALTIME_END_PERF_FORMAT=6989405.22228618 ############################################################################### ################################## TIME IS UP ################################# ############################################################################### joining threads... ############################################################################### ################################# END RUNNING ################################# ############################################################################### 10.1s 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.038s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8191 16352 32318 61560 107040 160899 195538 183323 128200 65440 24189 6528 1270 164 14 2 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 1 30 386 3076 16080 53181 126260 207753 238446 190960 106691 41850 11786 2376 314 26 4 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16382 32704 64636 123120 214080 321798 391076 366646 256400 130880 48378 13056 2540 328 28 4 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 1 30 386 3076 16080 53181 126260 207753 238446 190960 106691 41850 11786 2376 314 26 4 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 1.99988 1.99817 1.9882 1.95241 1.8694 1.75158 1.60764 1.46877 1.34966 1.25523 1.18482 1.13494 1.09727 1.06457 1.04268 1.07143 1 tree_stats_height=30 tree_stats_numInternals=999219 tree_stats_numLeaves=999220 tree_stats_numNodes=1998439 tree_stats_numKeys=999220 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=20.8889 tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917392 1831424 3619616 6894720 11988480 18020688 21900256 20532176 14358400 7329280 2709168 731136 142240 18368 1568 224 tree_stats_sizeInBytes=111912584 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=9671 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:0 2:0 4:0 8:0 16:2 32:25 64:8596 128:237454 256:233012 512:719 (2^04, 2^05]: 2 (2^05, 2^06]: 25 (2^06, 2^07]: 8596 (2^07, 2^08]: 237454 (2^08, 2^09]: 233012 (2^09, 2^10]: 719 sum_limbo_reclamation_event_size_total=132477492 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=840864 first_thread_announced_epoch_by_thread=19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 19342 sum_num_inserts_by_thread=1607117 1610878 1620246 1615824 1608442 1603010 1608598 1602705 1622111 1623680 1605699 1599276 1601972 1611766 1615952 1620634 1605253 1601490 1602283 1607059 1618521 1610033 1609167 1597699 1600917 1610086 1611849 1612338 1614342 1597668 1606952 1607168 1625854 1618306 1603356 1605784 1604486 1613161 1624933 1621328 1608497 1599683 1601390 1607396 1618101 1619284 1602216 1600295 sum_num_inserts_total=77264805 sum_num_deletes_by_thread=1606890 1612386 1619269 1616928 1608597 1599932 1608334 1605127 1623416 1623964 1606137 1602262 1602866 1614348 1617162 1618643 1605105 1603074 1600997 1609800 1617049 1611022 1608411 1599328 1600539 1608162 1613654 1610732 1615863 1595027 1607584 1606320 1627033 1619143 1605767 1605172 1602522 1615948 1623416 1620585 1607831 1602311 1602488 1606651 1615688 1618628 1600942 1598867 sum_num_deletes_total=77271920 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3214007 3223264 3239515 3232752 3217039 3202942 3216932 3207832 3245527 3247644 3211836 3201538 3204838 3226114 3233114 3239277 3210358 3204564 3203280 3216859 3235570 3221055 3217578 3197027 3201456 3218248 3225503 3223070 3230205 3192695 3214536 3213488 3252887 3237449 3209123 3210956 3207008 3229109 3248349 3241913 3216328 3201994 3203878 3214047 3233789 3237912 3203158 3199162 average_num_operations_total=3219515 stdev_num_operations_total=2267 sum_num_operations_total=154536725 min_num_operations_total=3192695 max_num_operations_total=3252887 first_time_thread_terminate_by_thread=10000199 10000285 10000223 10000289 10000278 10000283 10000173 10000261 10000209 10000162 10000186 10000265 10000184 10000220 10000203 10000211 10000189 10000194 10000174 10000271 10000146 10000244 10000251 10000193 10000215 10000273 10000145 10000277 10000260 10000277 10000198 10000266 10000173 10000146 10000251 10000276 10000206 10000190 10000145 10000258 10000175 10000262 10000252 10000186 10000250 10000283 10000180 10000254 min_time_thread_terminate_total=10000145 max_time_thread_terminate_total=10000289 first_time_thread_start_by_thread=3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 4 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 4 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=999153416962 threads_final_size=999220 final_keysum=999153416962 final_size=999220 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=77264805 total_deletes=77271920 total_updates=154536725 total_queries=0 total_ops=154536725 find_throughput=0 rq_throughput=0 update_throughput=15453672 query_throughput=0 total_throughput=15453672 total find : 0 total rq : 0 total inserts : 77264805 total deletes : 77271920 total updates : 154536725 total queries : 0 total ops : 154536725 find throughput : 0 rq throughput : 0 update throughput : 15453672 query throughput : 0 total throughput : 15453672 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=50.7035 PAPI_L3_TCM=10.8198 PAPI_TOT_CYC=8604.04 PAPI_TOT_INS=2005.8 garbage=0 total_execution_walltime=11.529s REALTIME_MAIN_END_PERF_FORMAT=6989405.258671153 [time_cmd_output] time_elapsed_sec=11.66, faults_major=0, faults_minor=131174, mem_maxresident_kb=526328, user_cputime=558.82, sys_cputime=6.08, percent_cpu=4844% 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