__step=000241 __cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 180 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_int_bst_tm_auto.debra.tl2 -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=data000241.txt __path_data=data/data000241.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6997459.264614140 binary=./brown_int_bst_tm_auto.debra.tl2 parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_int_bst_tm_auto 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=32 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 TL2 system ready: GV=GV4 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.006s prefilling_size=999903 prefilling_elapsed_millis=1.001s finished prefilling to size 999876 for expected size 1000000 keysum=999668626565, performing 17045695 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=999876 pref_millis=1102 prefill_elapsed_ms=1119 4NodeIxPvL9tx_safety0EE_object_size=32 4NodeIxPvL9tx_safety0EE_allocated_count=0 4NodeIxPvL9tx_safety0EE_allocated_size=0MB 4NodeIxPvL9tx_safety0EE_get_from_pool=0 4NodeIxPvL9tx_safety0EE_deallocated=0 4NodeIxPvL9tx_safety0EE_limbo_count=0 4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 global_epoch_counter=781 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6997460.574294148 REALTIME_END_PERF_FORMAT=6997470.574444534 ############################################################################### ################################## 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 798 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 63 122 230 417 741 1307 2192 3467 5300 7859 11303 15592 20661 26273 32084 37624 42085 45533 47548 47618 46291 43159 39109 34397 29892 25375 21199 17050 13412 10237 7731 5680 4054 2838 1879 1225 740 476 308 192 121 73 36 17 6 2 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 1 2 9 28 57 92 225 503 894 1484 2416 3758 5635 8169 10996 14117 17759 20517 23030 25190 25892 26097 24889 22923 20462 18091 15591 13268 10863 8591 6666 5075 3812 2785 1951 1294 886 514 336 218 137 81 59 27 13 5 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 124 239 445 798 1399 2417 3970 6194 9343 13719 19350 26296 34442 43080 51741 59844 66050 70578 72808 72183 69256 63998 57320 50354 43466 36790 30318 24275 18828 14397 10755 7866 5623 3830 2519 1626 990 644 410 258 154 95 44 19 7 2 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 124 239 445 798 1399 2417 3970 6194 9343 13719 19350 26296 34442 43080 51741 59844 66050 70578 72808 72183 69256 63998 57320 50354 43466 36790 30318 24275 18828 14397 10755 7866 5623 3830 2519 1626 990 644 410 258 154 95 44 19 7 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.95312 1.94355 1.89958 1.85618 1.82456 1.79342 1.73562 1.6869 1.65273 1.62721 1.58656 1.55318 1.52407 1.48798 1.45629 1.42945 1.40046 1.37918 1.3579 1.33739 1.31815 1.3009 1.28456 1.27838 1.26957 1.26262 1.24787 1.23831 1.22311 1.22095 1.21004 1.20325 1.19947 1.17642 1.1671 1.15919 1.15375 1.1697 1.15839 1.16098 1.12791 1.14286 1.08421 1.04545 1.05263 1 1 tree_stats_height=53 tree_stats_numInternals=653581 tree_stats_numLeaves=345410 tree_stats_numNodes=998991 tree_stats_numKeys=998991 tree_stats_avgDegreeInternal=1.52849 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34576 tree_stats_avgKeyDepth=25.9354 4NodeIxPvL9tx_safety0EE_object_size=32 4NodeIxPvL9tx_safety0EE_allocated_count=0 4NodeIxPvL9tx_safety0EE_allocated_size=0MB 4NodeIxPvL9tx_safety0EE_get_from_pool=0 4NodeIxPvL9tx_safety0EE_deallocated=0 4NodeIxPvL9tx_safety0EE_limbo_count=0 4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 global_epoch_counter=12739 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:53 2:1364 4:50907 8:372008 16:55566 [2^00, 2^01]: 53 (2^01, 2^02]: 1364 (2^02, 2^03]: 50907 (2^03, 2^04]: 372008 (2^04, 2^05]: 55566 sum_limbo_reclamation_event_size_total=5511518 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9997 9998 9998 9998 9998 9997 9998 9998 9998 9997 count_limbo_reclamation_event_size_total=479898 sum_limbo_reclamation_event_count_total=573984 first_thread_announced_epoch_by_thread=25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 25478 sum_num_inserts_by_thread=270636 271333 275110 276640 277662 272730 270645 273506 278917 278193 276537 273750 272950 272372 277143 277944 275953 273135 271099 272702 274432 278758 274183 270494 272297 272636 276816 276571 275235 273011 270411 275004 277684 279837 274423 273370 272709 274166 275822 278624 277448 272826 271587 271230 274692 279695 275424 272707 sum_num_inserts_total=13181049 sum_num_deletes_by_thread=269702 272248 274179 276815 276740 271650 269940 272766 279352 277626 276630 273186 272681 273062 277488 279194 276466 273481 271559 272350 274853 279879 274311 271533 271468 274141 277032 276525 274883 273522 271221 274756 277266 279845 274922 274056 273553 273338 275341 279453 277977 273039 271294 273021 275434 279650 274135 273806 sum_num_deletes_total=13187369 sum_num_searches_by_thread=4851695 4884170 4949004 4976848 4988872 4887471 4875983 4913780 5025241 5016300 4983919 4919718 4919192 4911332 4989290 5017035 4977966 4922206 4879330 4909986 4946030 5026204 4946315 4879629 4883904 4928805 4986500 4980026 4949406 4921900 4873474 4944600 4992828 5034143 4938909 4916696 4917150 4930008 4958310 5018929 5002892 4910514 4877952 4901065 4956265 5027503 4946272 4913322 sum_num_searches_total=237308889 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=5392033 5427751 5498293 5530303 5543274 5431851 5416568 5460052 5583510 5572119 5537086 5466654 5464823 5456766 5543921 5574173 5530385 5468822 5421988 5455038 5495315 5584841 5494809 5421656 5427669 5475582 5540348 5533122 5499524 5468433 5415106 5494360 5547778 5593825 5488254 5464122 5463412 5477512 5509473 5577006 5558317 5456379 5420833 5445316 5506391 5586848 5495831 5459835 average_num_operations_total=5493277 stdev_num_operations_total=7967 sum_num_operations_total=263677307 min_num_operations_total=5392033 max_num_operations_total=5593825 first_time_thread_terminate_by_thread=10000151 10000151 10000151 10000151 10000150 10000150 10000150 10000150 10000151 10000150 10000150 10000150 10000150 10000151 10000150 10000151 10000150 10000151 10000150 10000151 10000151 10000151 10000150 10000150 10000151 10000151 10000151 10000151 10000150 10000150 10000150 10000150 10000151 10000150 10000150 10000150 10000150 10000151 10000150 10000151 10000150 10000150 10000150 10000151 10000151 10000151 10000150 10000150 min_time_thread_terminate_total=10000150 max_time_thread_terminate_total=10000151 first_time_thread_start_by_thread=3 3 3 2 2 3 3 2 2 3 3 2 3 3 3 3 3 3 3 3 3 3 3 2 3 3 3 2 2 3 3 2 2 3 3 2 3 3 3 3 3 3 3 3 3 3 3 2 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=998922241472 threads_final_size=998991 final_keysum=998922241472 final_size=998991 validate_result=success Validation OK. Structural validation OK. total_find=237308889 total_rq=0 total_inserts=13181049 total_deletes=13187369 total_updates=26368418 total_queries=237308889 total_ops=263677307 find_throughput=23730888 rq_throughput=0 update_throughput=2636841 query_throughput=23730888 total_throughput=26367730 total find : 237308889 total rq : 0 total inserts : 13181049 total deletes : 13187369 total updates : 26368418 total queries : 237308889 total ops : 263677307 find throughput : 23730888 rq throughput : 0 update throughput : 2636841 query throughput : 23730888 total throughput : 26367730 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=21.3315 PAPI_L3_TCM=0.472423 PAPI_TOT_CYC=3549.72 PAPI_TOT_INS=2540.81 garbage=118631324342505 total_execution_walltime=11.428s REALTIME_MAIN_END_PERF_FORMAT=6997470.706530761 TL2 system shutdown: GCLOCK=0x1DB2E5E Starts=280902449 Aborts=179446 Overflows: R=0 W=0 L=0 [time_cmd_output] time_elapsed_sec=11.57, faults_major=0, faults_minor=94731, mem_maxresident_kb=344108, user_cputime=502.84, sys_cputime=59.42, percent_cpu=4858% timeout=false algorithm=brown_int_bst_tm_auto.debra.tl2 thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1