__step=000530 __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 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=data000530.txt __path_data=data/data000530.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6985274.420133732 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=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 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.004s prefilling_size=999770 prefilling_elapsed_millis=1.001s finished prefilling to size 999771 for expected size 1000000 keysum=999584824703, performing 16124004 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=999771 pref_millis=1102 prefill_elapsed_ms=1127 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=913 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=6985275.702362115 REALTIME_END_PERF_FORMAT=6985285.702503793 ############################################################################### ################################## 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.054s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16371 32432 62199 108988 163558 197222 183188 125523 63327 22867 6019 1161 144 13 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 13 310 2665 15410 54418 129894 211256 240853 187719 103787 39715 10877 2178 275 26 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32742 64864 124398 217976 327116 394444 366376 251046 126654 45734 12038 2322 288 26 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 13 310 2665 15410 54418 129894 211256 240853 187719 103787 39715 10877 2178 275 26 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99921 1.99053 1.95891 1.87612 1.75035 1.60291 1.46442 1.34261 1.25225 1.18055 1.13161 1.09644 1.06202 1.04514 1 tree_stats_height=29 tree_stats_numInternals=999395 tree_stats_numLeaves=999396 tree_stats_numNodes=1998791 tree_stats_numKeys=999396 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=20.8594 tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1833552 3632384 6966288 12206656 18318496 22088864 20517056 14058576 7092624 2561104 674128 130032 16128 1456 tree_stats_sizeInBytes=111932296 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=17215 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:11 4:262 8:23623 16:215606 32:228387 64:11775 128:93 256:47 (2^01, 2^02]: 11 (2^02, 2^03]: 262 (2^03, 2^04]: 23623 (2^04, 2^05]: 215606 (2^05, 2^06]: 228387 (2^06, 2^07]: 11775 (2^07, 2^08]: 93 (2^08, 2^09]: 47 sum_limbo_reclamation_event_size_total=16511197 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 9992 9996 9996 9996 count_limbo_reclamation_event_size_total=479804 sum_limbo_reclamation_event_count_total=1564992 first_thread_announced_epoch_by_thread=34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 34430 sum_num_inserts_by_thread=369039 371522 375237 374770 372342 369592 371743 372598 376212 376773 372750 370623 371690 374539 377551 376704 373589 371967 369364 372636 373079 375977 370725 368919 370162 372006 376019 374486 373134 368947 370898 371906 378060 376860 374039 371244 372806 373092 375781 375937 374420 371405 368625 372817 373324 376084 373372 369138 sum_num_inserts_total=17904503 sum_num_deletes_by_thread=368701 371808 375336 375708 372970 368612 371345 372427 376364 378029 372199 371765 371262 374890 376997 377384 374137 371750 369581 371883 375017 376073 372161 369615 370540 372324 375428 374198 374254 369124 371171 372965 376033 375994 373062 371236 373117 373799 375484 376252 373157 371653 368339 371143 373197 375659 372757 369744 sum_num_deletes_total=17906644 sum_num_searches_by_thread=6649842 6691621 6742004 6758921 6709355 6641578 6684955 6705531 6772251 6789125 6711166 6672827 6687760 6734978 6782018 6779228 6733966 6686450 6647555 6686670 6723675 6779299 6689222 6646922 6668290 6702662 6763228 6744491 6719715 6640025 6675372 6709037 6781802 6781016 6721900 6690236 6704705 6742712 6768653 6768319 6727671 6688714 6641947 6698246 6723246 6759173 6702894 6646197 sum_num_searches_total=322277170 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=7387582 7434951 7492577 7509399 7454667 7379782 7428043 7450556 7524827 7543927 7456115 7415215 7430712 7484407 7536566 7533316 7481692 7430167 7386500 7431189 7471771 7531349 7432108 7385456 7408992 7446992 7514675 7493175 7467103 7378096 7417441 7453908 7535895 7533870 7469001 7432716 7450628 7489603 7519918 7520508 7475248 7431772 7378911 7442206 7469767 7510916 7449023 7385079 average_num_operations_total=7460173 stdev_num_operations_total=7187 sum_num_operations_total=358088317 min_num_operations_total=7378096 max_num_operations_total=7543927 first_time_thread_terminate_by_thread=10000141 10000141 10000140 10000140 10000140 10000140 10000141 10000141 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000141 10000141 10000140 10000140 10000141 10000141 10000140 10000140 10000140 10000140 10000141 10000141 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000140 10000141 10000140 10000140 10000140 10000141 10000141 10000140 10000140 min_time_thread_terminate_total=10000140 max_time_thread_terminate_total=10000141 first_time_thread_start_by_thread=6 5 5 5 5 5 6 5 6 5 5 6 5 5 6 6 6 6 6 6 6 6 6 6 6 5 5 5 5 5 6 5 6 5 5 6 5 5 6 6 6 6 6 6 6 6 6 6 min_time_thread_start_total=5 max_time_thread_start_total=6 sum_duration_all_ops_total= threads_final_keysum=999612047697 threads_final_size=999396 final_keysum=999612047697 final_size=999396 validate_result=success Validation OK. Structural validation OK. total_find=322277170 total_rq=0 total_inserts=17904503 total_deletes=17906644 total_updates=35811147 total_queries=322277170 total_ops=358088317 find_throughput=32227717 rq_throughput=0 update_throughput=3581114 query_throughput=32227717 total_throughput=35808831 total find : 322277170 total rq : 0 total inserts : 17904503 total deletes : 17906644 total updates : 35811147 total queries : 322277170 total ops : 358088317 find throughput : 32227717 rq throughput : 0 update throughput : 3581114 query throughput : 32227717 total throughput : 35808831 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=18.5361 PAPI_L3_TCM=4.79017 PAPI_TOT_CYC=3736.05 PAPI_TOT_INS=406.823 garbage=161088907254883 total_execution_walltime=11.431s REALTIME_MAIN_END_PERF_FORMAT=6985285.852047540 [time_cmd_output] time_elapsed_sec=11.59, faults_major=0, faults_minor=130183, mem_maxresident_kb=522512, user_cputime=561.37, sys_cputime=2.54, percent_cpu=4864% 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=2