__step=000201 __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" ./sigouin_int_avl_kcas_validate.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000201.txt __path_data=data/data000201.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x562dde107400 addrof(kcasDescriptors[1])=0x562dde107d00 addrof(rdcssDescriptors[0])=0x562dde227400 addrof(rdcssDescriptors[1])=0x562dde2274c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6996845.400928965 binary=./sigouin_int_avl_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_avl_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=5 DEL_FRAC=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=5.0 5.0 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.003s prefilling_size=6332764 prefilling_elapsed_millis=1.001s prefilling_size=6644430 prefilling_elapsed_millis=1.101s prefilling_size=6928398 prefilling_elapsed_millis=1.201s prefilling_size=7185061 prefilling_elapsed_millis=1.301s prefilling_size=7418791 prefilling_elapsed_millis=1.402s prefilling_size=7632288 prefilling_elapsed_millis=1.502s prefilling_size=7827525 prefilling_elapsed_millis=1.602s prefilling_size=8005517 prefilling_elapsed_millis=1.702s prefilling_size=8167296 prefilling_elapsed_millis=1.802s prefilling_size=8314019 prefilling_elapsed_millis=1.903s prefilling_size=8449302 prefilling_elapsed_millis=2.003s prefilling_size=8575223 prefilling_elapsed_millis=2.103s prefilling_size=8688704 prefilling_elapsed_millis=2.203s prefilling_size=8792555 prefilling_elapsed_millis=2.303s prefilling_size=8887017 prefilling_elapsed_millis=2.404s prefilling_size=8975955 prefilling_elapsed_millis=2.504s prefilling_size=9058636 prefilling_elapsed_millis=2.604s prefilling_size=9133003 prefilling_elapsed_millis=2.704s prefilling_size=9200342 prefilling_elapsed_millis=2.804s prefilling_size=9263299 prefilling_elapsed_millis=2.905s prefilling_size=9320572 prefilling_elapsed_millis=3.005s prefilling_size=9373426 prefilling_elapsed_millis=3.105s prefilling_size=9421114 prefilling_elapsed_millis=3.205s prefilling_size=9467167 prefilling_elapsed_millis=3.305s prefilling_size=9506749 prefilling_elapsed_millis=3.406s prefilling_size=9545794 prefilling_elapsed_millis=3.506s prefilling_size=9579566 prefilling_elapsed_millis=3.606s prefilling_size=9612178 prefilling_elapsed_millis=3.706s prefilling_size=9641413 prefilling_elapsed_millis=3.806s prefilling_size=9669580 prefilling_elapsed_millis=3.907s prefilling_size=9695152 prefilling_elapsed_millis=4.007s prefilling_size=9718797 prefilling_elapsed_millis=4.107s prefilling_size=9739425 prefilling_elapsed_millis=4.207s prefilling_size=9757201 prefilling_elapsed_millis=4.307s prefilling_size=9775036 prefilling_elapsed_millis=4.408s prefilling_size=9791339 prefilling_elapsed_millis=4.508s prefilling_size=9807836 prefilling_elapsed_millis=4.608s finished prefilling to size 9807866 for expected size 10000000 keysum=98068989724096, performing 83952342 updates; total_prefilling_elapsed_ms=4709 ms) pref_size=9807866 pref_millis=4709 prefill_elapsed_ms=4730 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6996850.298140544 REALTIME_END_PERF_FORMAT=6996860.298273875 ############################################################################### ################################## 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.039s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262114 522003 990299 1513562 1421605 629599 101142 3742 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 28 2041 47157 387323 1251549 1615832 793986 129125 4617 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262142 524044 1037456 1900885 2673154 2245431 895128 132867 4617 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262142 524044 1037456 1900885 2673154 2245431 895128 132867 4617 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99998 1.99919 1.98361 1.87771 1.61003 1.30819 1.11825 1.03544 1.00659 1 tree_stats_height=27 tree_stats_numInternals=5706209 tree_stats_numLeaves=4231658 tree_stats_numNodes=9937867 tree_stats_numKeys=9937867 tree_stats_avgDegreeInternal=1.74159 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42581 tree_stats_avgKeyDepth=21.626 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:50 2:1527 4:54819 8:374257 16:49244 [2^00, 2^01]: 50 (2^01, 2^02]: 1527 (2^02, 2^03]: 54819 (2^03, 2^04]: 374257 (2^04, 2^05]: 49244 sum_limbo_reclamation_event_size_total=5424272 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9997 9998 9998 9997 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9997 9998 9998 9998 count_limbo_reclamation_event_size_total=479897 sum_limbo_reclamation_event_count_total=544896 first_thread_announced_epoch_by_thread=30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 30498 sum_num_inserts_by_thread=259726 257564 261759 259722 259293 258646 257417 258694 261229 261386 258261 259179 257943 258531 260470 262088 261319 259091 256370 259744 258975 262527 258969 257620 257213 257807 259242 261566 258853 259327 260309 260129 260480 261439 258677 259460 258323 259254 261086 262932 261053 257231 256019 257302 262046 261415 258095 255884 sum_num_inserts_total=12451665 sum_num_deletes_by_thread=259197 258339 262070 259661 259676 259098 256884 258561 261066 260820 258120 258719 257874 259024 259661 261006 261847 259441 256511 260144 259770 261808 260078 257000 256241 258034 259147 262495 258816 258568 258838 259293 261145 261068 258352 258729 257852 258662 261464 262656 262124 257332 255755 258194 260795 261673 256780 256256 sum_num_deletes_total=12446644 sum_num_searches_by_thread=4661728 4644729 4714704 4677265 4658361 4653879 4632217 4651166 4702601 4698510 4650213 4660223 4643648 4671304 4684341 4687282 4706214 4670531 4613690 4673899 4664662 4719208 4671937 4638735 4627251 4636120 4670208 4714157 4663313 4656534 4669859 4682046 4701907 4695999 4650423 4658330 4640794 4670537 4703832 4728168 4708251 4636321 4606078 4635602 4703241 4718082 4636496 4603560 sum_num_searches_total=224068156 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=5180651 5160632 5238533 5196648 5177330 5171623 5146518 5168421 5224896 5220716 5166594 5178121 5159465 5188859 5204472 5210376 5229380 5189063 5126571 5193787 5183407 5243543 5190984 5153355 5140705 5151961 5188597 5238218 5180982 5174429 5189006 5201468 5223532 5218506 5167452 5176519 5156969 5188453 5226382 5253756 5231428 5150884 5117852 5151098 5226082 5241170 5151371 5115700 average_num_operations_total=5186801 stdev_num_operations_total=5063 sum_num_operations_total=248966465 min_num_operations_total=5115700 max_num_operations_total=5253756 first_time_thread_terminate_by_thread=10000134 10000133 10000134 10000134 10000134 10000134 10000133 10000134 10000134 10000133 10000134 10000134 10000134 10000133 10000134 10000134 10000134 10000134 10000134 10000133 10000134 10000134 10000133 10000134 10000135 10000133 10000134 10000134 10000134 10000134 10000133 10000134 10000134 10000133 10000134 10000134 10000134 10000133 10000134 10000134 10000134 10000134 10000134 10000133 10000134 10000134 10000133 10000134 min_time_thread_terminate_total=10000133 max_time_thread_terminate_total=10000135 first_time_thread_start_by_thread=3 3 3 3 4 4 4 4 3 4 3 4 3 3 4 3 3 3 3 4 4 4 3 4 3 3 3 3 4 4 4 4 3 4 3 4 3 3 4 3 3 3 3 4 4 4 3 4 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=99355716232993 threads_final_size=9937867 final_keysum=99355716232993 final_size=9937867 validate_result=success Validation OK. Structural validation OK. total_find=224068156 total_rq=0 total_inserts=12451665 total_deletes=12446644 total_updates=24898309 total_queries=224068156 total_ops=248966465 find_throughput=22406815 rq_throughput=0 update_throughput=2489830 query_throughput=22406815 total_throughput=24896646 total find : 224068156 total rq : 0 total inserts : 12451665 total deletes : 12446644 total updates : 24898309 total queries : 224068156 total ops : 248966465 find throughput : 22406815 rq throughput : 0 update throughput : 2489830 query throughput : 22406815 total throughput : 24896646 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=27.5711 PAPI_L3_TCM=9.4425 PAPI_TOT_CYC=5374.79 PAPI_TOT_INS=1379.22 garbage=1108599842000453 total_execution_walltime=15.032s REALTIME_MAIN_END_PERF_FORMAT=6996860.433897836 [time_cmd_output] time_elapsed_sec=15.22, faults_major=0, faults_minor=234794, mem_maxresident_kb=941044, user_cputime=733.02, sys_cputime=2.55, percent_cpu=4831% timeout=false algorithm=sigouin_int_avl_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