__step=000817 __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" ./ellen_ext_bst_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=data000817.txt __path_data=data/data000817.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6988830.952695666 binary=./ellen_ext_bst_lf.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=ellen_ext_bst_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=40 descriptor=64 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=999671 prefilling_elapsed_millis=1.001s finished prefilling to size 999712 for expected size 1000000 keysum=999854123955, performing 26860673 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=999712 pref_millis=1102 prefill_elapsed_ms=1121 6node_tIxPvE_object_size=40 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=1309 6info_tIxPvE_object_size=64 6info_tIxPvE_allocated_count=0 6info_tIxPvE_allocated_size=0MB 6info_tIxPvE_get_from_pool=0 6info_tIxPvE_deallocated=0 6info_tIxPvE_limbo_count=0 6info_tIxPvE_limbo_details=0 0 0 global_epoch_counter=0 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6988832.241914997 REALTIME_END_PERF_FORMAT=6988842.242047408 ############################################################################### ################################## 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 998 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.026s tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 64 128 255 499 961 1829 3307 5688 9243 14457 21856 31875 43951 57070 70328 81683 89853 94133 93472 87629 77304 63622 49482 36107 25101 16238 10148 6051 3538 2014 1149 674 408 268 163 94 45 21 4 1 0 tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 0 0 1 11 37 93 351 926 2133 4029 7058 11837 19799 30832 43812 58973 73513 85573 94794 99315 97954 90986 77762 62857 47113 33964 22328 14245 8564 5062 2879 1624 940 548 373 232 143 69 38 7 2 tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 128 256 510 998 1922 3658 6614 11376 18486 28914 43712 63750 87902 114140 140656 163366 179706 188266 186944 175258 154608 127244 98964 72214 50202 32476 20296 12102 7076 4028 2298 1348 816 536 326 188 90 42 8 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 1 11 37 93 351 926 2133 4029 7058 11837 19799 30832 43812 58973 73513 85573 94794 99315 97954 90986 77762 62857 47113 33964 22328 14245 8564 5062 2879 1624 940 548 373 232 143 69 38 7 2 tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 2 2 1.99609 1.97843 1.96293 1.95161 1.90405 1.85999 1.8125 1.78205 1.7559 1.7292 1.68943 1.64925 1.61616 1.58073 1.55001 1.52382 1.49649 1.46874 1.44109 1.41151 1.38887 1.36485 1.34759 1.32345 1.31248 1.29814 1.29235 1.28462 1.28525 1.2933 1.30267 1.32843 1.3041 1.28834 1.23936 1.23333 1.09524 1.125 1 tree_stats_height=49 tree_stats_numInternals=1000778 tree_stats_numLeaves=1000779 tree_stats_numNodes=2001557 tree_stats_numKeys=1000777 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=0.999998 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=27.182 tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 5120 10240 20400 39920 76880 146320 264560 455040 739440 1156560 1748480 2550000 3516080 4565600 5626240 6534640 7188240 7530640 7477760 7010320 6184320 5089760 3958560 2888560 2008080 1299040 811840 484080 283040 161120 91920 53920 32640 21440 13040 7520 3600 1680 320 80 tree_stats_sizeInBytes=80062280 6node_tIxPvE_object_size=40 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=13892 6info_tIxPvE_object_size=64 6info_tIxPvE_allocated_count=0 6info_tIxPvE_allocated_size=0MB 6info_tIxPvE_get_from_pool=0 6info_tIxPvE_deallocated=0 6info_tIxPvE_limbo_count=0 6info_tIxPvE_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:0 32:2 64:32 128:241244 256:238522 512:8 (2^05, 2^06]: 2 (2^06, 2^07]: 32 (2^07, 2^08]: 241244 (2^08, 2^09]: 238522 (2^09, 2^10]: 8 sum_limbo_reclamation_event_size_total=128821740 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=1207968 first_thread_announced_epoch_by_thread=27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 27784 sum_num_inserts_by_thread=2690985 2702008 2711499 2719287 2708160 2684449 2690531 2703739 2723952 2733714 2705486 2688887 2702867 2706170 2723062 2723052 2710095 2698037 2683818 2698720 2709286 2722123 2692785 2680185 2681229 2699211 2716933 2718719 2711287 2684786 2691870 2708184 2726299 2730489 2703257 2693103 2700732 2708096 2724814 2722119 2705058 2691893 2682612 2693331 2710309 2715343 2694035 2677007 sum_num_inserts_total=129803613 sum_num_deletes_by_thread=2688952 2700811 2710802 2715443 2705241 2683482 2689843 2704661 2724100 2735285 2701025 2688721 2699032 2705793 2723208 2721598 2710303 2695636 2679710 2701032 2708745 2721936 2691322 2678851 2679618 2699840 2714593 2723231 2710279 2685493 2693300 2704607 2728303 2729879 2702309 2694300 2700721 2709136 2723510 2724972 2702197 2694805 2680637 2690724 2712159 2718473 2694419 2675931 sum_num_deletes_total=129778968 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=5379937 5402819 5422301 5434730 5413401 5367931 5380374 5408400 5448052 5468999 5406511 5377608 5401899 5411963 5446270 5444650 5420398 5393673 5363528 5399752 5418031 5444059 5384107 5359036 5360847 5399051 5431526 5441950 5421566 5370279 5385170 5412791 5454602 5460368 5405566 5387403 5401453 5417232 5448324 5447091 5407255 5386698 5363249 5384055 5422468 5433816 5388454 5352938 average_num_operations_total=5407970 stdev_num_operations_total=4366 sum_num_operations_total=259582581 min_num_operations_total=5352938 max_num_operations_total=5468999 first_time_thread_terminate_by_thread=10000132 10000131 10000131 10000131 10000131 10000131 10000131 10000131 10000132 10000131 10000131 10000131 10000131 10000131 10000132 10000131 10000131 10000132 10000132 10000131 10000131 10000131 10000132 10000131 10000132 10000132 10000132 10000132 10000131 10000131 10000132 10000131 10000132 10000131 10000131 10000131 10000132 10000131 10000131 10000131 10000131 10000131 10000132 10000131 10000131 10000131 10000131 10000131 min_time_thread_terminate_total=10000131 max_time_thread_terminate_total=10000132 first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 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=1000901963260 threads_final_size=1000777 final_keysum=1000901963260 final_size=1000777 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=129803613 total_deletes=129778968 total_updates=259582581 total_queries=0 total_ops=259582581 find_throughput=0 rq_throughput=0 update_throughput=25958258 query_throughput=0 total_throughput=25958258 total find : 0 total rq : 0 total inserts : 129803613 total deletes : 129778968 total updates : 259582581 total queries : 0 total ops : 259582581 find throughput : 0 rq throughput : 0 update throughput : 25958258 query throughput : 0 total throughput : 25958258 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=36.5628 PAPI_L3_TCM=7.26965 PAPI_TOT_CYC=5150.4 PAPI_TOT_INS=1066.31 garbage=0 total_execution_walltime=11.414s REALTIME_MAIN_END_PERF_FORMAT=6988842.367615431 [time_cmd_output] time_elapsed_sec=11.53, faults_major=0, faults_minor=100767, mem_maxresident_kb=404852, user_cputime=556.59, sys_cputime=2.80, percent_cpu=4850% timeout=false algorithm=ellen_ext_bst_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