__step=000193 __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 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000193.txt __path_data=data/data000193.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6981135.315855237 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=2000000 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.018s prefilling_size=999587 prefilling_elapsed_millis=1.001s finished prefilling to size 999541 for expected size 1000000 keysum=999761986030, performing 16179975 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=999541 pref_millis=1102 prefill_elapsed_ms=1115 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=6981136.626636188 REALTIME_END_PERF_FORMAT=6981146.626782185 ############################################################################### ################################## 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.04s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8190 16339 32174 60845 104521 153967 185686 178329 132974 75493 31746 9562 2057 310 27 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 2 41 504 3503 17169 55075 122248 193043 223684 190455 119240 53930 17067 3804 593 54 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16380 32678 64348 121690 209042 307934 371372 356658 265948 150986 63492 19124 4114 620 54 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 2 41 504 3503 17169 55075 122248 193043 223684 190455 119240 53930 17067 3804 593 54 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 1.99976 1.9975 1.98458 1.94556 1.85891 1.73654 1.60301 1.48019 1.37283 1.28386 1.21026 1.1506 1.10756 1.07535 1.04355 1 tree_stats_height=29 tree_stats_numInternals=1000411 tree_stats_numLeaves=1000412 tree_stats_numNodes=2000823 tree_stats_numKeys=1000412 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=20.9897 tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917280 1829968 3603488 6814640 11706352 17244304 20796832 19972848 14893088 8455216 3555552 1070944 230384 34720 3024 tree_stats_sizeInBytes=112046088 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=18122 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:82783 2:136303 4:147602 8:45130 16:1416 [2^00, 2^01]: 82783 (2^01, 2^02]: 136303 (2^02, 2^03]: 147602 (2^03, 2^04]: 45130 (2^04, 2^05]: 1416 sum_limbo_reclamation_event_size_total=1653130 count_limbo_reclamation_event_size_by_thread=8554 8543 8559 8596 8597 8636 8643 8530 8611 8661 8623 8597 8623 8674 8689 8614 8586 8652 8652 8550 8576 8722 8622 8522 8598 8575 8624 8751 8547 8531 8535 8555 8731 8652 8572 8549 8604 8681 8685 8683 8690 8532 8564 8598 8582 8603 8609 8551 count_limbo_reclamation_event_size_total=413234 sum_limbo_reclamation_event_count_total=1652064 first_thread_announced_epoch_by_thread=36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 36244 sum_num_inserts_by_thread=39323 39695 39441 39523 39595 39067 39292 39269 40213 40389 39628 39231 39503 39326 40150 39926 39489 39628 38864 39488 39801 39489 39593 38847 39066 39417 39784 39645 39545 38683 39447 39289 40243 39991 39529 39410 39606 39533 40023 40198 39701 39507 39019 39252 40023 39837 39264 39234 sum_num_inserts_total=1898016 sum_num_deletes_by_thread=39226 39516 39591 39540 39456 39089 39555 39363 39896 39844 39295 39147 39354 39819 39998 39805 39665 39644 39115 39201 39692 39914 39438 39097 39371 39405 39872 39859 39450 38895 39358 39682 40178 40053 39256 39268 39363 39687 39831 40185 39176 39519 39238 39334 39900 39926 39429 39080 sum_num_deletes_total=1897575 sum_num_searches_by_thread=7751431 7796382 7865544 7861533 7831683 7738690 7776648 7811118 7913721 7919120 7832876 7791087 7794957 7838918 7895491 7887369 7853329 7798416 7749332 7802288 7850257 7879577 7807039 7738336 7758007 7802882 7871214 7874680 7836709 7745419 7795430 7815005 7904967 7918339 7830318 7783134 7801363 7832985 7891494 7889315 7855738 7796988 7737076 7786974 7843746 7888680 7807345 7741291 sum_num_searches_total=375594241 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=7829980 7875593 7944576 7940596 7910734 7816846 7855495 7889750 7993830 7999353 7911799 7869465 7873814 7918063 7975639 7967100 7932483 7877688 7827311 7880977 7929750 7958980 7886070 7816280 7836444 7881704 7950870 7954184 7915704 7822997 7874235 7893976 7985388 7998383 7909103 7861812 7880332 7912205 7971348 7969698 7934615 7876014 7815333 7865560 7923669 7968443 7886038 7819605 average_num_operations_total=7903954 stdev_num_operations_total=7818 sum_num_operations_total=379389832 min_num_operations_total=7815333 max_num_operations_total=7999353 first_time_thread_terminate_by_thread=10000146 10000146 10000145 10000146 10000146 10000145 10000145 10000145 10000145 10000146 10000145 10000146 10000146 10000146 10000145 10000146 10000145 10000145 10000146 10000146 10000145 10000146 10000146 10000145 10000146 10000146 10000145 10000146 10000146 10000145 10000145 10000145 10000145 10000146 10000145 10000146 10000146 10000146 10000145 10000146 10000145 10000145 10000146 10000146 10000145 10000146 10000146 10000145 min_time_thread_terminate_total=10000145 max_time_thread_terminate_total=10000146 first_time_thread_start_by_thread=4 4 4 4 3 4 4 4 3 4 4 3 4 4 3 4 4 4 4 4 4 4 4 3 4 4 4 4 3 4 4 4 3 4 4 3 4 4 3 4 4 4 4 4 4 4 4 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=1000414729705 threads_final_size=1000412 final_keysum=1000414729705 final_size=1000412 validate_result=success Validation OK. Structural validation OK. total_find=375594241 total_rq=0 total_inserts=1898016 total_deletes=1897575 total_updates=3795591 total_queries=375594241 total_ops=379389832 find_throughput=37559424 rq_throughput=0 update_throughput=379559 query_throughput=37559424 total_throughput=37938983 total find : 375594241 total rq : 0 total inserts : 1898016 total deletes : 1897575 total updates : 3795591 total queries : 375594241 total ops : 379389832 find throughput : 37559424 rq throughput : 0 update throughput : 379559 query throughput : 37559424 total throughput : 37938983 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=16.9667 PAPI_L3_TCM=4.13728 PAPI_TOT_CYC=3527.23 PAPI_TOT_INS=334.184 garbage=187870918601456 total_execution_walltime=11.449s REALTIME_MAIN_END_PERF_FORMAT=6981146.772428384 [time_cmd_output] time_elapsed_sec=11.59, faults_major=0, faults_minor=129295, mem_maxresident_kb=518920, user_cputime=561.31, sys_cputime=1.78, percent_cpu=4855% 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