__step=000425 __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_htm.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=data000425.txt __path_data=data/data000425.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55d298944400 addrof(kcasDescriptors[1])=0x55d298944d00 addrof(rdcssDescriptors[0])=0x55d298a64400 addrof(rdcssDescriptors[1])=0x55d298a644c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6999920.486219651 binary=./sigouin_int_avl_kcas_validate_htm.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_htm 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=1000558 prefilling_elapsed_millis=1.001s finished prefilling to size 1000592 for expected size 1000000 keysum=1000443782746, performing 33856936 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=1000592 pref_millis=1102 prefill_elapsed_ms=1123 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6999921.758693468 REALTIME_END_PERF_FORMAT=6999931.758834801 ############################################################################### ################################## 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.054s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32761 64967 118111 154404 118157 46273 6718 136 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 7 504 10379 66256 144564 137125 58557 8496 163 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65471 128490 220660 262721 183398 65275 8632 163 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65471 128490 220660 262721 183398 65275 8632 163 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99823 1.97025 1.79811 1.49088 1.24833 1.10361 1.02932 1.00313 1 tree_stats_height=24 tree_stats_numInternals=574294 tree_stats_numLeaves=426051 tree_stats_numNodes=1000345 tree_stats_numKeys=1000345 tree_stats_avgDegreeInternal=1.74187 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.4259 tree_stats_avgKeyDepth=18.339 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:61 2:1403 4:53498 8:374423 16:50514 [2^00, 2^01]: 61 (2^01, 2^02]: 1403 (2^02, 2^03]: 53498 (2^03, 2^04]: 374423 (2^04, 2^05]: 50514 sum_limbo_reclamation_event_size_total=5449426 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9997 9998 9998 9998 9998 9998 9997 9997 9998 9998 9998 count_limbo_reclamation_event_size_total=479899 sum_limbo_reclamation_event_count_total=1097328 first_thread_announced_epoch_by_thread=48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 48900 sum_num_inserts_by_thread=512871 517088 520312 518243 520274 515349 515846 515713 524479 527765 518067 514968 516929 519915 524849 522966 523295 518755 516945 517688 521593 525853 517816 515051 514871 518617 523268 521662 520415 513131 515989 520192 526536 523704 520841 515980 515365 520411 523049 524846 520322 519576 513749 518665 522247 524431 519586 513617 sum_num_inserts_total=24933700 sum_num_deletes_by_thread=513304 518358 520926 518392 520847 514201 515296 516739 526645 526221 518524 515498 516571 519322 522833 524014 522748 517644 516151 517733 523194 525044 516840 514252 514830 519223 522838 522794 520067 512043 514948 518909 526329 524496 519999 515858 514898 519957 522911 524846 521238 518528 514404 519182 520359 525435 518651 512682 sum_num_deletes_total=24926722 sum_num_searches_by_thread=9222886 9310545 9370992 9353480 9353006 9260068 9269031 9301888 9451844 9478726 9334117 9284749 9310979 9355147 9422751 9435848 9409392 9316640 9279540 9322226 9383941 9458725 9308248 9259415 9269998 9342717 9408154 9389807 9363877 9235652 9285039 9355939 9470571 9434561 9358782 9285032 9270315 9384643 9419098 9452102 9384076 9350777 9274926 9332191 9405220 9457149 9328879 9250545 sum_num_searches_total=448764234 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=10249061 10345991 10412230 10390115 10394127 10289618 10300173 10334340 10502968 10532712 10370708 10315215 10344479 10394384 10470433 10482828 10455435 10353039 10312636 10357647 10428728 10509622 10342904 10288718 10299699 10380557 10454260 10434263 10404359 10260826 10315976 10395040 10523436 10482761 10399622 10316870 10300578 10425011 10465058 10501794 10425636 10388881 10303079 10370038 10447826 10507015 10367116 10276844 average_num_operations_total=10388013 stdev_num_operations_total=11180 sum_num_operations_total=498624656 min_num_operations_total=10249061 max_num_operations_total=10532712 first_time_thread_terminate_by_thread=10000141 10000141 10000141 10000141 10000142 10000141 10000142 10000141 10000141 10000141 10000142 10000142 10000142 10000141 10000141 10000142 10000142 10000141 10000142 10000141 10000141 10000141 10000142 10000142 10000141 10000141 10000141 10000141 10000142 10000141 10000142 10000141 10000141 10000141 10000142 10000141 10000142 10000141 10000141 10000142 10000142 10000141 10000142 10000141 10000141 10000141 10000142 10000142 min_time_thread_terminate_total=10000141 max_time_thread_terminate_total=10000142 first_time_thread_start_by_thread=3 3 2 3 2 3 3 3 2 3 3 3 3 3 2 3 3 3 3 3 2 3 2 2 3 3 2 3 2 3 3 3 2 3 3 3 3 3 2 3 3 3 3 3 2 3 2 2 min_time_thread_start_total=2 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=999942321297 threads_final_size=1000345 final_keysum=999942321297 final_size=1000345 validate_result=success Validation OK. Structural validation OK. total_find=448764234 total_rq=0 total_inserts=24933700 total_deletes=24926722 total_updates=49860422 total_queries=448764234 total_ops=498624656 find_throughput=44876423 rq_throughput=0 update_throughput=4986042 query_throughput=44876423 total_throughput=49862465 total find : 448764234 total rq : 0 total inserts : 24933700 total deletes : 24926722 total updates : 49860422 total queries : 448764234 total ops : 498624656 find throughput : 44876423 rq throughput : 0 update throughput : 4986042 query throughput : 44876423 total throughput : 49862465 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=15.0105 PAPI_L3_TCM=2.07939 PAPI_TOT_CYC=2669.02 PAPI_TOT_INS=1153.9 garbage=224363885352774 total_execution_walltime=11.408s REALTIME_MAIN_END_PERF_FORMAT=6999931.895100911 [time_cmd_output] time_elapsed_sec=11.54, faults_major=0, faults_minor=91187, mem_maxresident_kb=366988, user_cputime=558.80, sys_cputime=2.22, percent_cpu=4860% timeout=false algorithm=sigouin_int_avl_kcas_validate_htm.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1