__step=000265 __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" ./guerraoui_ext_bst_ticket.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=data000265.txt __path_data=data/data000265.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6981978.732464601 binary=./guerraoui_ext_bst_ticket.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=guerraoui_ext_bst_ticket 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=40 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.02s prefilling_size=99474 prefilling_elapsed_millis=1.001s finished prefilling to size 99486 for expected size 100000 keysum=9957887358, performing 66107319 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=99486 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=3281 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6981980.18047498 REALTIME_END_PERF_FORMAT=6981990.18118123 ############################################################################### ################################## 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 870 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.031s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 125 238 435 765 1283 2010 3058 4475 6088 7717 9188 9923 9956 9461 8622 7420 5999 4404 2997 1944 1261 848 609 403 275 169 91 58 44 26 20 13 6 1 2 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 3 12 41 105 247 556 962 1641 2862 4459 6246 8453 9890 10451 10300 9824 8841 7594 5811 4050 2627 1674 1087 815 531 381 247 124 72 62 32 27 20 11 0 4 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 250 476 870 1530 2566 4020 6116 8950 12176 15434 18376 19846 19912 18922 17244 14840 11998 8808 5994 3888 2522 1696 1218 806 550 338 182 116 88 52 40 26 12 2 4 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 2 12 41 105 247 556 962 1641 2862 4459 6246 8453 9890 10451 10300 9824 8841 7594 5811 4050 2627 1674 1087 815 531 381 247 124 72 62 32 27 20 11 0 4 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.96875 1.952 1.91387 1.87931 1.83856 1.78332 1.7607 1.73169 1.68022 1.63379 1.59531 1.54 1.50166 1.47514 1.45566 1.43029 1.40425 1.36706 1.34026 1.32432 1.32433 1.33624 1.35908 1.33087 1.34119 1.30727 1.26923 1.31868 1.37931 1.29545 1.38462 1.325 1.23077 1.08333 2 1 tree_stats_height=43 tree_stats_numInternals=100061 tree_stats_numLeaves=100062 tree_stats_numNodes=200123 tree_stats_numKeys=100061 tree_stats_avgDegreeInternal=2.00001 tree_stats_avgDegreeLeaves=0.99999 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=21.064 tree_stats_bytesAtDepth=40 80 160 320 640 1280 2560 5120 10000 19040 34800 61200 102640 160800 244640 358000 487040 617360 735040 793840 796480 756880 689760 593600 479920 352320 239760 155520 100880 67840 48720 32240 22000 13520 7280 4640 3520 2080 1600 1040 480 80 160 tree_stats_sizeInBytes=8004920 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=48499 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:175257 4:139859 8:14118 16:16 (2^01, 2^02]: 175257 (2^02, 2^03]: 139859 (2^03, 2^04]: 14118 (2^04, 2^05]: 16 sum_limbo_reclamation_event_size_total=1108032 count_limbo_reclamation_event_size_by_thread=6769 6813 6927 6853 6823 6782 6772 6821 6894 6848 6806 6841 6809 6911 6906 6965 6870 6922 6898 6984 6845 6894 6870 6806 6758 6765 6895 6921 6895 6787 6789 6900 6921 6927 6756 6849 6812 6883 6954 6912 6854 6910 6829 6883 6921 6905 6766 6829 count_limbo_reclamation_event_size_total=329250 sum_limbo_reclamation_event_count_total=2170464 first_thread_announced_epoch_by_thread=96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 96998 sum_num_inserts_by_thread=103734 104164 105978 105698 104446 103034 102660 104977 105605 106263 105096 103670 103628 103997 104836 106121 105201 105646 103762 105036 105280 106647 103765 103745 102298 104090 105747 104334 105072 103502 103674 104359 105934 105616 104214 104551 104189 104587 105934 105622 104650 105039 103328 105048 105674 105452 104433 104324 sum_num_inserts_total=5024660 sum_num_deletes_by_thread=103443 104681 104514 105154 104399 103425 102890 104730 105334 106045 104142 104237 103969 104691 104532 105484 105262 104738 104349 105448 105190 106270 104167 103471 102246 104072 105715 104574 104279 103273 103887 104471 105754 106646 104033 104262 104063 105048 106110 106665 104866 104938 103296 105223 105485 106248 103982 103528 sum_num_deletes_total=5023229 sum_num_searches_by_thread=20494079 20616487 20851697 20794487 20687781 20423684 20406335 20695642 20879579 20980729 20661526 20627492 20580165 20639365 20755156 20961286 20853315 20849109 20603360 20835490 20847540 21001507 20661828 20499467 20340046 20582471 20860480 20717065 20725749 20469264 20554416 20728754 20942418 21028029 20598758 20608843 20607401 20711129 20928049 21004165 20756065 20702851 20454665 20724727 20889612 20908672 20657948 20558719 sum_num_searches_total=994267402 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=20701256 20825332 21062189 21005339 20896626 20630143 20611885 20905349 21090518 21193037 20870764 20835399 20787762 20848053 20964524 21172891 21063778 21059493 20811471 21045974 21058010 21214424 20869760 20706683 20544590 20790633 21071942 20925973 20935100 20676039 20761977 20937584 21154106 21240291 20807005 20817656 20815653 20920764 21140093 21216452 20965581 20912828 20661289 20934998 21100771 21120372 20866363 20766571 average_num_operations_total=20923235 stdev_num_operations_total=25612 sum_num_operations_total=1004315291 min_num_operations_total=20544590 max_num_operations_total=21240291 first_time_thread_terminate_by_thread=10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000070 10000069 10000069 10000069 10000069 10000068 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000068 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 min_time_thread_terminate_total=10000068 max_time_thread_terminate_total=10000070 first_time_thread_start_by_thread=4 4 3 4 3 4 3 3 3 4 3 3 4 4 4 3 3 3 3 3 4 3 3 3 4 4 3 4 3 4 3 3 3 4 3 3 4 4 4 3 3 3 3 3 4 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=10026363327 threads_final_size=100061 final_keysum=10026363327 final_size=100061 validate_result=success Validation OK. Structural validation OK. total_find=994267402 total_rq=0 total_inserts=5024660 total_deletes=5023229 total_updates=10047889 total_queries=994267402 total_ops=1004315291 find_throughput=99426740 rq_throughput=0 update_throughput=1004788 query_throughput=99426740 total_throughput=100431529 total find : 994267402 total rq : 0 total inserts : 5024660 total deletes : 5023229 total updates : 10047889 total queries : 994267402 total ops : 1004315291 find throughput : 99426740 rq throughput : 0 update throughput : 1004788 query throughput : 99426740 total throughput : 100431529 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=8.75977 PAPI_L3_TCM=0.0004245 PAPI_TOT_CYC=1332.72 PAPI_TOT_INS=283.125 garbage=49720116603418 total_execution_walltime=11.406s REALTIME_MAIN_END_PERF_FORMAT=6981990.139847148 [time_cmd_output] time_elapsed_sec=11.52, faults_major=0, faults_minor=77361, mem_maxresident_kb=311552, user_cputime=560.18, sys_cputime=2.97, percent_cpu=4886% timeout=false algorithm=guerraoui_ext_bst_ticket.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1