__step=000084 __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 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000084.txt __path_data=data/data000084.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6979810.945577206 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=20000000 PREFILL_THREADS=96 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=96 WORK_THREADS=96 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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143 Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.008s prefilling_size=6226487 prefilling_elapsed_millis=1.001s prefilling_size=6532949 prefilling_elapsed_millis=1.101s prefilling_size=6809826 prefilling_elapsed_millis=1.201s prefilling_size=7060031 prefilling_elapsed_millis=1.301s prefilling_size=7292552 prefilling_elapsed_millis=1.402s prefilling_size=7501873 prefilling_elapsed_millis=1.502s prefilling_size=7700955 prefilling_elapsed_millis=1.602s prefilling_size=7876671 prefilling_elapsed_millis=1.702s prefilling_size=8036078 prefilling_elapsed_millis=1.802s prefilling_size=8181474 prefilling_elapsed_millis=1.902s prefilling_size=8318670 prefilling_elapsed_millis=2.003s prefilling_size=8444905 prefilling_elapsed_millis=2.103s prefilling_size=8557815 prefilling_elapsed_millis=2.203s prefilling_size=8663542 prefilling_elapsed_millis=2.303s prefilling_size=8763402 prefilling_elapsed_millis=2.403s prefilling_size=8852705 prefilling_elapsed_millis=2.504s prefilling_size=8936410 prefilling_elapsed_millis=2.604s prefilling_size=9011813 prefilling_elapsed_millis=2.704s prefilling_size=9083456 prefilling_elapsed_millis=2.804s prefilling_size=9149427 prefilling_elapsed_millis=2.904s prefilling_size=9211777 prefilling_elapsed_millis=3.005s prefilling_size=9267301 prefilling_elapsed_millis=3.105s prefilling_size=9320128 prefilling_elapsed_millis=3.205s prefilling_size=9370769 prefilling_elapsed_millis=3.305s prefilling_size=9415036 prefilling_elapsed_millis=3.405s prefilling_size=9456035 prefilling_elapsed_millis=3.506s prefilling_size=9492859 prefilling_elapsed_millis=3.606s prefilling_size=9528287 prefilling_elapsed_millis=3.706s prefilling_size=9560475 prefilling_elapsed_millis=3.806s prefilling_size=9590228 prefilling_elapsed_millis=3.906s prefilling_size=9619842 prefilling_elapsed_millis=4.007s prefilling_size=9647037 prefilling_elapsed_millis=4.107s prefilling_size=9671220 prefilling_elapsed_millis=4.207s prefilling_size=9692955 prefilling_elapsed_millis=4.307s prefilling_size=9714891 prefilling_elapsed_millis=4.408s prefilling_size=9734513 prefilling_elapsed_millis=4.508s prefilling_size=9753849 prefilling_elapsed_millis=4.608s prefilling_size=9771108 prefilling_elapsed_millis=4.708s prefilling_size=9786257 prefilling_elapsed_millis=4.808s prefilling_size=9800351 prefilling_elapsed_millis=4.908s finished prefilling to size 9800402 for expected size 10000000 keysum=98010180576071, performing 79079074 updates; total_prefilling_elapsed_ms=5010 ms) pref_size=9800402 pref_millis=5010 prefill_elapsed_ms=5041 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=1583 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=6979816.171999274 REALTIME_END_PERF_FORMAT=6979826.172156024 ############################################################################### ################################## 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.067s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32767 65524 130754 257560 485922 835958 1254475 1592488 1686893 1471699 1042330 586619 256897 83738 19673 3080 290 14 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 10 294 3948 29198 135886 417441 916462 1498083 1902087 1901068 1498041 916341 430056 147803 36266 5870 566 27 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65534 131048 261508 515120 971844 1671916 2508950 3184976 3373786 2943398 2084660 1173238 513794 167476 39346 6160 580 28 2 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 10 294 3948 29198 135886 417441 916462 1498083 1902087 1901068 1498041 916341 430056 147803 36266 5870 566 27 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99997 1.99985 1.99776 1.9849 1.94332 1.86018 1.75032 1.63472 1.52964 1.43622 1.35412 1.2814 1.21896 1.16298 1.11747 1.07828 1.04708 1.02414 1.03571 1 tree_stats_height=35 tree_stats_numInternals=9839449 tree_stats_numLeaves=9839450 tree_stats_numNodes=19678899 tree_stats_numKeys=9839450 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=24.5153 tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3669904 7338688 14644448 28846720 54423264 93627296 140501200 178358656 188932016 164830288 116740960 65701328 28772464 9378656 2203376 344960 32480 1568 112 tree_stats_sizeInBytes=1102018344 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=6462 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:48246 2:158760 4:356066 8:301835 16:52052 32:100 [2^00, 2^01]: 48246 (2^01, 2^02]: 158760 (2^02, 2^03]: 356066 (2^03, 2^04]: 301835 (2^04, 2^05]: 52052 (2^05, 2^06]: 100 sum_limbo_reclamation_event_size_total=6531814 count_limbo_reclamation_event_size_by_thread=9523 9536 9552 9554 9568 9553 9532 9535 9574 9574 9552 9562 9538 9530 9578 9563 9560 9544 9548 9570 9537 9549 9544 9488 9560 9576 9548 9546 9584 9548 9575 9556 9574 9578 9550 9557 9545 9562 9561 9548 9555 9586 9553 9541 9544 9545 9559 9497 9570 9551 9520 9556 9552 9538 9541 9564 9545 9579 9545 9543 9530 9570 9547 9554 9563 9544 9534 9554 9552 9560 9550 9548 9549 9551 9552 9540 9568 9507 9575 9581 9557 9555 9546 9538 9571 9562 9564 9573 9569 9553 9548 9567 9542 9577 9549 9543 count_limbo_reclamation_event_size_total=917059 sum_limbo_reclamation_event_count_total=936768 first_thread_announced_epoch_by_thread=12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 12924 sum_num_inserts_by_thread=22419 22728 22721 22901 22421 22445 22307 22952 22728 22912 22631 22595 22648 22814 23126 23195 22810 22595 22437 22670 22684 22834 22468 22356 22223 22765 22922 22723 22833 22609 22472 22955 22797 23011 22585 22840 22841 22749 23262 23145 22915 22870 22161 22705 23074 22898 22492 22034 22829 22718 22794 23070 22521 22689 22616 22644 22900 23094 22929 22895 22776 22886 22823 23310 22658 22533 22273 22670 23059 22827 23157 22433 22361 22616 22980 22847 22594 22799 22634 22790 22944 23050 22671 22584 22538 22567 22736 23035 22693 22628 22465 22564 22582 22855 22962 22333 sum_num_inserts_total=2182210 sum_num_deletes_by_thread=22438 22498 22773 22774 22550 22489 22531 22685 22791 22778 22700 22494 22438 22656 23153 22997 22574 22492 22567 22171 22567 22824 22584 22241 22553 22534 22701 22903 22644 22610 22680 22850 22931 22922 22716 22692 22649 22673 22784 23019 22955 22632 22588 22538 22709 22822 22483 22251 22544 22881 22795 22727 22558 22481 22690 22522 23056 23325 22956 22638 22643 22795 22898 22794 23104 22546 22663 22533 22959 23322 22376 22511 22689 22683 22794 22772 22624 22485 22657 22662 22882 22802 22843 22464 22750 22797 22897 22948 22994 22309 22703 22596 22842 22820 22999 22587 sum_num_deletes_total=2179520 sum_num_searches_by_thread=4462790 4485960 4529791 4503500 4506459 4442504 4475001 4492623 4554837 4559289 4500122 4471368 4489097 4517704 4546053 4541448 4508763 4472146 4449377 4474890 4509335 4530980 4468342 4428310 4461259 4481478 4535194 4519909 4500718 4440940 4475590 4495054 4555898 4551351 4494605 4463233 4488160 4510570 4548696 4545893 4510443 4472861 4448758 4474773 4512202 4531751 4471127 4426807 4481664 4498611 4542207 4532796 4491387 4463484 4481331 4498454 4559856 4557061 4515561 4481209 4492536 4522111 4554073 4547782 4519062 4458993 4442464 4472841 4536913 4542859 4495760 4466973 4480372 4504399 4547535 4544365 4496908 4473014 4477452 4498814 4560141 4554098 4516876 4477381 4493916 4519075 4552285 4546831 4511987 4451704 4442918 4470324 4538871 4546179 4500343 4467267 sum_num_searches_total=432167002 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=4507647 4531186 4575285 4549175 4551430 4487438 4519839 4538260 4600356 4604979 4545453 4516457 4534183 4563174 4592332 4587640 4554147 4517233 4494381 4519731 4554586 4576638 4513394 4472907 4506035 4526777 4580817 4565535 4546195 4486159 4520742 4540859 4601626 4597284 4539906 4508765 4533650 4555992 4594742 4592057 4556313 4518363 4493507 4520016 4557985 4577471 4516102 4471092 4527037 4544210 4587796 4578593 4536466 4508654 4526637 4543620 4605812 4603480 4561446 4526742 4537955 4567792 4599794 4593886 4564824 4504072 4487400 4518044 4582931 4589008 4541293 4511917 4525422 4549698 4593309 4589984 4542126 4518298 4522743 4544266 4605967 4599950 4562390 4522429 4539204 4564439 4597918 4592814 4557674 4496641 4488086 4515484 4584295 4591854 4546304 4512187 average_num_operations_total=4547174 stdev_num_operations_total=3656 sum_num_operations_total=436528732 min_num_operations_total=4471092 max_num_operations_total=4605967 first_time_thread_terminate_by_thread=10000156 10000156 10000157 10000157 10000156 10000156 10000157 10000156 10000156 10000156 10000156 10000156 10000156 10000156 10000156 10000158 10000156 10000156 10000158 10000158 10000156 10000158 10000157 10000157 10000156 10000156 10000156 10000157 10000156 10000156 10000157 10000156 10000157 10000156 10000156 10000156 10000156 10000156 10000156 10000158 10000156 10000156 10000157 10000157 10000156 10000158 10000156 10000157 10000156 10000157 10000157 10000158 10000157 10000156 10000156 10000156 10000158 10000156 10000158 10000156 10000157 10000155 10000158 10000157 10000157 10000157 10000157 10000155 10000156 10000155 10000156 10000155 10000158 10000157 10000157 10000158 10000157 10000156 10000156 10000156 10000158 10000156 10000158 10000156 10000157 10000155 10000158 10000157 10000157 10000155 10000157 10000155 10000156 10000155 10000156 10000155 min_time_thread_terminate_total=10000155 max_time_thread_terminate_total=10000158 first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 3 3 4 4 3 3 3 4 4 4 4 3 4 4 3 4 4 3 3 4 3 3 4 4 3 3 4 4 3 3 3 4 4 4 4 3 4 4 3 4 4 3 3 4 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=98400503757647 threads_final_size=9839450 final_keysum=98400503757647 final_size=9839450 validate_result=success Validation OK. Structural validation OK. total_find=432167002 total_rq=0 total_inserts=2182210 total_deletes=2179520 total_updates=4361730 total_queries=432167002 total_ops=436528732 find_throughput=43216700 rq_throughput=0 update_throughput=436173 query_throughput=43216700 total_throughput=43652873 total find : 432167002 total rq : 0 total inserts : 2182210 total deletes : 2179520 total updates : 4361730 total queries : 432167002 total ops : 436528732 find throughput : 43216700 rq throughput : 0 update throughput : 436173 query throughput : 43216700 total throughput : 43652873 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=33.7178 PAPI_L3_TCM=12.7262 PAPI_TOT_CYC=6123.61 PAPI_TOT_INS=357.541 garbage=2122720922773968 total_execution_walltime=15.41s REALTIME_MAIN_END_PERF_FORMAT=6979826.371059793 [time_cmd_output] time_elapsed_sec=15.72, faults_major=0, faults_minor=592756, mem_maxresident_kb=2368044, user_cputime=1448.57, sys_cputime=8.67, percent_cpu=9266% 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