__step=000073 __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" ./sigouin_int_bst_kcas_validate.debra -nwork 48 -nprefill 48 -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=data000073.txt __path_data=data/data000073.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x5649fbed4400 addrof(kcasDescriptors[1])=0x5649fbed4d00 addrof(rdcssDescriptors[0])=0x5649fbff4400 addrof(rdcssDescriptors[1])=0x5649fbff44c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6979659.500319345 binary=./sigouin_int_bst_kcas_validate.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=sigouin_int_bst_kcas_validate 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=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 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=7020420 prefilling_elapsed_millis=1.001s prefilling_size=7313721 prefilling_elapsed_millis=1.101s prefilling_size=7575600 prefilling_elapsed_millis=1.201s prefilling_size=7810922 prefilling_elapsed_millis=1.301s prefilling_size=8023248 prefilling_elapsed_millis=1.402s prefilling_size=8213789 prefilling_elapsed_millis=1.502s prefilling_size=8385065 prefilling_elapsed_millis=1.602s prefilling_size=8538787 prefilling_elapsed_millis=1.702s prefilling_size=8679134 prefilling_elapsed_millis=1.802s prefilling_size=8806188 prefilling_elapsed_millis=1.903s prefilling_size=8919010 prefilling_elapsed_millis=2.003s prefilling_size=9023444 prefilling_elapsed_millis=2.103s prefilling_size=9114841 prefilling_elapsed_millis=2.203s prefilling_size=9198112 prefilling_elapsed_millis=2.303s prefilling_size=9275084 prefilling_elapsed_millis=2.404s prefilling_size=9343260 prefilling_elapsed_millis=2.504s prefilling_size=9404635 prefilling_elapsed_millis=2.604s prefilling_size=9459846 prefilling_elapsed_millis=2.704s prefilling_size=9510884 prefilling_elapsed_millis=2.804s prefilling_size=9557314 prefilling_elapsed_millis=2.905s prefilling_size=9598243 prefilling_elapsed_millis=3.005s prefilling_size=9636788 prefilling_elapsed_millis=3.105s prefilling_size=9670721 prefilling_elapsed_millis=3.205s prefilling_size=9700963 prefilling_elapsed_millis=3.306s prefilling_size=9730257 prefilling_elapsed_millis=3.406s prefilling_size=9755279 prefilling_elapsed_millis=3.506s prefilling_size=9776972 prefilling_elapsed_millis=3.606s prefilling_size=9797260 prefilling_elapsed_millis=3.706s prefilling_size=9815794 prefilling_elapsed_millis=3.807s finished prefilling to size 9815826 for expected size 10000000 keysum=98138705779806, performing 80415805 updates; total_prefilling_elapsed_ms=3908 ms) pref_size=9815826 pref_millis=3908 prefill_elapsed_ms=3931 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6979663.602547663 REALTIME_END_PERF_FORMAT=6979673.602706361 ############################################################################### ################################## 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 1011 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.161s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 510 1000 1922 3586 6470 11243 18907 30437 47168 70198 100371 138171 182991 233966 288535 343133 394093 437037 469039 485752 484674 467013 433422 387800 334983 278761 223683 172791 129285 93397 65049 44005 28757 18112 11002 6674 3845 2165 1213 622 303 130 52 13 3 1 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 11 44 143 404 959 1977 4016 7328 12885 21284 33319 49887 70397 95443 124226 154606 185387 213785 237937 255237 263532 261699 250657 229923 203567 173840 142616 112186 85363 63021 44382 30688 20331 12996 7835 4892 2860 1621 940 485 238 104 46 10 2 1 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 510 1011 1966 3729 6874 12202 20884 34453 54496 83083 121655 171490 232878 304363 383978 467359 548699 622424 682824 723689 739911 730545 695121 638457 564906 482328 397523 315407 241471 178760 128070 88387 59445 38443 23998 14509 8737 5025 2834 1562 788 368 156 59 13 3 1 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 510 1011 1966 3729 6874 12202 20884 34453 54496 83083 121655 171490 232878 304363 383978 467359 548699 622424 682824 723689 739911 730545 695121 638457 564906 482328 397523 315407 241471 178760 128070 88387 59445 38443 23998 14509 8737 5025 2834 1562 788 368 156 59 13 3 1 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99219 1.98235 1.95549 1.91913 1.88174 1.83387 1.79012 1.7444 1.69831 1.65904 1.61934 1.5846 1.55226 1.52118 1.49287 1.46571 1.43985 1.41613 1.39489 1.37294 1.3512 1.3323 1.31224 1.29496 1.2774 1.26083 1.24623 1.23074 1.21775 1.20489 1.19396 1.18223 1.17469 1.16294 1.15311 1.14614 1.14219 1.13506 1.13313 1.12315 1.10627 1.08249 1.07065 1.04487 1 1 1 1 tree_stats_height=56 tree_stats_numInternals=6452795 tree_stats_numLeaves=3383110 tree_stats_numNodes=9835905 tree_stats_numKeys=9835905 tree_stats_avgDegreeInternal=1.52429 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34396 tree_stats_avgKeyDepth=29.0924 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:176065 2:135615 4:13148 8:6 [2^00, 2^01]: 176065 (2^01, 2^02]: 135615 (2^02, 2^03]: 13148 (2^03, 2^04]: 6 sum_limbo_reclamation_event_size_total=540380 count_limbo_reclamation_event_size_by_thread=6746 6763 6701 6799 6709 6687 6760 6770 6833 6809 6678 6815 6779 6765 6879 6852 6712 6794 6771 6717 6770 6725 6804 6774 6760 6749 6774 6810 6830 6683 6699 6763 6729 6771 6776 6797 6832 6784 6836 6777 6815 6741 6742 6721 6745 6768 6782 6738 count_limbo_reclamation_event_size_total=324834 sum_limbo_reclamation_event_count_total=514608 first_thread_announced_epoch_by_thread=28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 28766 sum_num_inserts_by_thread=24309 24858 24803 24680 24756 24402 24457 24457 24743 24941 24534 24624 24851 24865 24690 24690 24651 24329 24606 24547 24817 24702 24359 24181 24247 24346 25075 24598 24600 24402 24403 24331 24840 24818 24537 24484 24365 24631 24611 24835 24680 24342 24332 24059 24562 24470 24444 24386 sum_num_inserts_total=1179250 sum_num_deletes_by_thread=24568 24699 24612 24800 24918 24129 24336 24660 24818 25056 24525 24396 24609 24656 24820 24884 24499 24776 24497 24506 24657 24656 24539 24231 24317 24202 24817 24588 24899 24136 24381 24580 24627 24836 24737 24564 24708 24438 24902 24605 24750 24299 24234 24468 24626 24615 24717 24282 sum_num_deletes_total=1180175 sum_num_searches_by_thread=4826910 4861994 4894080 4893264 4875744 4822992 4840618 4854205 4919669 4919141 4858647 4833624 4853720 4877355 4914215 4902135 4880360 4848452 4812794 4835757 4879012 4890240 4838799 4803789 4833058 4829561 4890973 4888759 4875596 4826654 4849169 4854731 4922348 4918033 4855106 4836021 4845801 4873697 4897050 4901164 4884612 4853669 4816887 4836329 4881053 4886602 4853098 4817639 sum_num_searches_total=233465126 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=4875787 4911551 4943495 4942744 4925418 4871523 4889411 4903322 4969230 4969138 4907706 4882644 4903180 4926876 4963725 4951709 4929510 4897557 4861897 4884810 4928486 4939598 4887697 4852201 4881622 4878109 4940865 4937945 4925095 4875192 4897953 4903642 4971815 4967687 4904380 4885069 4894874 4922766 4946563 4950604 4934042 4902310 4865453 4884856 4930241 4935687 4902259 4866307 average_num_operations_total=4913011 stdev_num_operations_total=4711 sum_num_operations_total=235824551 min_num_operations_total=4852201 max_num_operations_total=4971815 first_time_thread_terminate_by_thread=10000159 10000160 10000159 10000159 10000159 10000160 10000160 10000159 10000159 10000159 10000160 10000160 10000160 10000160 10000160 10000159 10000160 10000159 10000159 10000159 10000160 10000160 10000160 10000160 10000159 10000160 10000159 10000159 10000159 10000159 10000160 10000159 10000160 10000159 10000160 10000160 10000159 10000160 10000160 10000160 10000160 10000159 10000159 10000159 10000160 10000160 10000160 10000159 min_time_thread_terminate_total=10000159 max_time_thread_terminate_total=10000160 first_time_thread_start_by_thread=5 4 5 5 4 5 5 5 5 4 5 5 4 5 4 5 5 5 5 4 5 5 5 4 5 4 5 5 4 5 5 5 5 4 5 5 4 5 4 5 5 5 5 4 5 5 5 4 min_time_thread_start_total=4 max_time_thread_start_total=5 sum_duration_all_ops_total= threads_final_keysum=98343114222275 threads_final_size=9835905 final_keysum=98343114222275 final_size=9835905 validate_result=success Validation OK. Structural validation OK. total_find=233465126 total_rq=0 total_inserts=1179250 total_deletes=1180175 total_updates=2359425 total_queries=233465126 total_ops=235824551 find_throughput=23346512 rq_throughput=0 update_throughput=235942 query_throughput=23346512 total_throughput=23582455 total find : 233465126 total rq : 0 total inserts : 1179250 total deletes : 1180175 total updates : 2359425 total queries : 233465126 total ops : 235824551 find throughput : 23346512 rq throughput : 0 update throughput : 235942 query throughput : 23346512 total throughput : 23582455 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=31.1412 PAPI_L3_TCM=9.91298 PAPI_TOT_CYC=5674.45 PAPI_TOT_INS=1497.07 garbage=1146983073532503 total_execution_walltime=14.371s REALTIME_MAIN_END_PERF_FORMAT=6979673.872418076 [time_cmd_output] time_elapsed_sec=14.56, faults_major=0, faults_minor=233593, mem_maxresident_kb=936324, user_cputime=700.30, sys_cputime=3.21, percent_cpu=4831% timeout=false algorithm=sigouin_int_bst_kcas_validate.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1