__step=000553 __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_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 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000553.txt __path_data=data/data000553.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55f20f3ff400 addrof(kcasDescriptors[1])=0x55f20f3ffd00 addrof(rdcssDescriptors[0])=0x55f20f51f400 addrof(rdcssDescriptors[1])=0x55f20f51f4c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6985544.441556608 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=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=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.004s prefilling_size=1000853 prefilling_elapsed_millis=1.001s finished prefilling to size 1000856 for expected size 1000000 keysum=1000336110540, performing 28774647 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=1000856 pref_millis=1102 prefill_elapsed_ms=1123 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6985545.706140950 REALTIME_END_PERF_FORMAT=6985555.706299507 ############################################################################### ################################## 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.046s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65337 122785 169462 118343 30607 2045 8 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 190 7061 63044 168253 145671 39099 2552 9 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65527 129846 232506 286596 176278 41144 2560 9 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65527 129846 232506 286596 176278 41144 2560 9 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99973 1.98446 1.84501 1.50379 1.20215 1.05977 1.01252 1.00039 1 tree_stats_height=24 tree_stats_numInternals=574122 tree_stats_numLeaves=425879 tree_stats_numNodes=1000001 tree_stats_numKeys=1000001 tree_stats_avgDegreeInternal=1.74179 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42588 tree_stats_avgKeyDepth=18.2498 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:41 2:1500 4:54583 8:377050 16:46672 32:1 64:0 128:47 [2^00, 2^01]: 41 (2^01, 2^02]: 1500 (2^02, 2^03]: 54583 (2^03, 2^04]: 377050 (2^04, 2^05]: 46672 (2^05, 2^06]: 1 (2^06, 2^07]: 0 (2^07, 2^08]: 47 sum_limbo_reclamation_event_size_total=5410055 count_limbo_reclamation_event_size_by_thread=9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9996 9998 9997 9998 9998 9998 9998 9998 9998 9998 9997 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9996 9998 9998 9997 9998 9998 count_limbo_reclamation_event_size_total=479894 sum_limbo_reclamation_event_count_total=1058491 first_thread_announced_epoch_by_thread=46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 46836 sum_num_inserts_by_thread=491591 495794 498982 502962 499532 493277 496582 496455 502551 502138 498883 493359 493487 500334 500370 500929 497110 496119 493627 493329 498048 499638 497707 493280 495433 496682 501036 499503 497929 491544 497281 496396 504411 504725 497882 494474 497717 500517 500019 502441 498119 493575 494138 492941 499057 504087 495536 494373 sum_num_inserts_total=23885900 sum_num_deletes_by_thread=491412 494995 497334 501776 500558 492403 496677 495115 502178 502543 499109 493910 493044 501030 501210 501424 496797 495923 494651 494329 498418 500262 498061 492785 494969 496164 502523 501338 496449 491751 497153 497915 505924 504513 497386 493301 496600 501265 501698 503125 498265 494789 493589 494025 500133 504107 495155 493421 sum_num_deletes_total=23891502 sum_num_searches_by_thread=8853551 8908576 8956158 9047706 9001054 8857211 8947220 8931281 9047598 9036699 8989854 8889169 8897060 9006426 9011077 9010246 8957555 8946059 8912471 8899208 8980026 9006234 8965612 8857447 8912408 8948844 9033980 9004959 8960002 8862319 8960526 8953108 9081309 9073456 8953605 8891726 8947561 9017062 9010122 9056747 8957287 8894906 8888480 8889137 8993348 9069708 8914183 8894179 sum_num_searches_total=430084460 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=9836554 9899365 9952474 10052444 10001144 9842891 9940479 9922851 10052327 10041380 9987846 9876438 9883591 10007790 10012657 10012599 9951462 9938101 9900749 9886866 9976492 10006134 9961380 9843512 9902810 9941690 10037539 10005800 9954380 9845614 9954960 9947419 10091644 10082694 9948873 9879501 9941878 10018844 10011839 10062313 9953671 9883270 9876207 9876103 9992538 10077902 9904874 9881973 average_num_operations_total=9955455 stdev_num_operations_total=10218 sum_num_operations_total=477861862 min_num_operations_total=9836554 max_num_operations_total=10091644 first_time_thread_terminate_by_thread=10000158 10000158 10000158 10000158 10000159 10000159 10000158 10000159 10000158 10000158 10000159 10000162 10000158 10000159 10000159 10000159 10000159 10000159 10000159 10000159 10000158 10000159 10000158 10000158 10000158 10000158 10000158 10000158 10000159 10000159 10000158 10000159 10000158 10000158 10000159 10000159 10000158 10000159 10000158 10000159 10000159 10000159 10000159 10000159 10000158 10000159 10000158 10000158 min_time_thread_terminate_total=10000158 max_time_thread_terminate_total=10000162 first_time_thread_start_by_thread=3 3 3 2 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=1000127872806 threads_final_size=1000001 final_keysum=1000127872806 final_size=1000001 validate_result=success Validation OK. Structural validation OK. total_find=430084460 total_rq=0 total_inserts=23885900 total_deletes=23891502 total_updates=47777402 total_queries=430084460 total_ops=477861862 find_throughput=43008446 rq_throughput=0 update_throughput=4777740 query_throughput=43008446 total_throughput=47786186 total find : 430084460 total rq : 0 total inserts : 23885900 total deletes : 23891502 total updates : 47777402 total queries : 430084460 total ops : 477861862 find throughput : 43008446 rq throughput : 0 update throughput : 4777740 query throughput : 43008446 total throughput : 47786186 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=15.1717 PAPI_L3_TCM=2.10331 PAPI_TOT_CYC=2792.42 PAPI_TOT_INS=1235.07 garbage=210642036477776 total_execution_walltime=11.396s REALTIME_MAIN_END_PERF_FORMAT=6985555.838838905 [time_cmd_output] time_elapsed_sec=11.52, faults_major=0, faults_minor=91231, mem_maxresident_kb=366768, user_cputime=559.74, sys_cputime=2.38, percent_cpu=4877% 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