__step=000417 __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 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000417.txt __path_data=data/data000417.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55cf493ee400 addrof(kcasDescriptors[1])=0x55cf493eed00 addrof(rdcssDescriptors[0])=0x55cf4950e400 addrof(rdcssDescriptors[1])=0x55cf4950e4c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6999826.443204820 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.006s prefilling_size=1000438 prefilling_elapsed_millis=1.001s finished prefilling to size 1000461 for expected size 1000000 keysum=1000336895364, performing 28663933 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=1000461 pref_millis=1102 prefill_elapsed_ms=1123 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6999827.733718797 REALTIME_END_PERF_FORMAT=6999837.733864529 ############################################################################### ################################## 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.032s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32762 65013 118691 161328 121098 38732 4321 103 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 6 473 9853 62103 153724 145072 49066 5469 130 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65486 128544 223431 274822 183804 53387 5572 130 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65486 128544 223431 274822 183804 53387 5572 130 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99866 1.97015 1.81482 1.50796 1.22817 1.07973 1.02343 1.00485 1 tree_stats_height=24 tree_stats_numInternals=574815 tree_stats_numLeaves=425896 tree_stats_numNodes=1000711 tree_stats_numKeys=1000711 tree_stats_avgDegreeInternal=1.74093 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42559 tree_stats_avgKeyDepth=18.3037 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:57 2:1486 4:54499 8:377680 16:46172 32:2 [2^00, 2^01]: 57 (2^01, 2^02]: 1486 (2^02, 2^03]: 54499 (2^03, 2^04]: 377680 (2^04, 2^05]: 46172 (2^05, 2^06]: 2 sum_limbo_reclamation_event_size_total=5398363 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9997 9998 9997 9997 9998 9998 9998 9998 9997 9998 count_limbo_reclamation_event_size_total=479896 sum_limbo_reclamation_event_count_total=1059696 first_thread_announced_epoch_by_thread=46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 46878 sum_num_inserts_by_thread=494923 497065 500501 499745 497872 491412 493553 498329 501667 503713 496098 496360 494565 496078 500186 501599 497349 496370 491017 494855 499276 500521 495624 491812 494953 496667 500332 499551 494360 492576 492006 497515 501978 502882 497826 495099 496571 499543 500354 499618 499230 495074 489555 494914 498558 501191 496457 492808 sum_num_inserts_total=23860108 sum_num_deletes_by_thread=495333 497587 499215 499233 497091 491172 492217 497893 501735 502522 496293 493769 495025 495474 499627 501478 497666 496047 490625 495068 498085 500816 496874 490807 494966 496901 501059 499623 495394 493627 492373 497339 503496 503372 498099 495606 496568 498854 501180 500313 497590 494893 489804 495310 498681 501857 499103 493286 sum_num_deletes_total=23860946 sum_num_searches_by_thread=8905157 8949168 8990130 8994470 8945336 8842311 8888062 8951944 9027636 9057664 8950987 8900250 8894553 8932429 8994808 9030067 8951372 8937502 8841304 8916116 8977740 9012744 8926489 8834447 8902180 8945243 9002778 9001475 8919301 8860204 8872991 8960607 9048509 9082958 8953654 8917651 8939949 8979662 9017495 8997905 8953480 8909061 8818151 8902468 8972206 9003499 8955636 8869864 sum_num_searches_total=429439613 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=9895413 9943820 9989846 9993448 9940299 9824895 9873832 9948166 10031038 10063899 9943378 9890379 9884143 9923981 9994621 10033144 9946387 9929919 9822946 9906039 9975101 10014081 9918987 9817066 9892099 9938811 10004169 10000649 9909055 9846407 9857370 9955461 10053983 10089212 9949579 9908356 9933088 9978059 10019029 9997836 9950300 9899028 9797510 9892692 9969445 10006547 9951196 9855958 average_num_operations_total=9940847 stdev_num_operations_total=9855 sum_num_operations_total=477160667 min_num_operations_total=9797510 max_num_operations_total=10089212 first_time_thread_terminate_by_thread=10000145 10000145 10000145 10000145 10000145 10000145 10000145 10000145 10000145 10000146 10000146 10000145 10000145 10000146 10000145 10000145 10000145 10000145 10000145 10000145 10000145 10000146 10000146 10000145 10000145 10000145 10000145 10000145 10000145 10000145 10000145 10000145 10000145 10000146 10000146 10000145 10000145 10000146 10000145 10000145 10000145 10000146 10000145 10000145 10000145 10000146 10000146 10000145 min_time_thread_terminate_total=10000145 max_time_thread_terminate_total=10000146 first_time_thread_start_by_thread=3 3 4 3 3 3 4 3 3 3 4 3 3 4 4 4 4 4 4 3 4 4 4 4 3 3 4 3 3 3 4 3 3 3 4 3 3 4 4 4 4 4 4 3 4 4 4 4 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=1000920591472 threads_final_size=1000711 final_keysum=1000920591472 final_size=1000711 validate_result=success Validation OK. Structural validation OK. total_find=429439613 total_rq=0 total_inserts=23860108 total_deletes=23860946 total_updates=47721054 total_queries=429439613 total_ops=477160667 find_throughput=42943961 rq_throughput=0 update_throughput=4772105 query_throughput=42943961 total_throughput=47716066 total find : 429439613 total rq : 0 total inserts : 23860108 total deletes : 23860946 total updates : 47721054 total queries : 429439613 total ops : 477160667 find throughput : 42943961 rq throughput : 0 update throughput : 4772105 query throughput : 42943961 total throughput : 47716066 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=15.2801 PAPI_L3_TCM=2.10287 PAPI_TOT_CYC=2798.38 PAPI_TOT_INS=1237.18 garbage=214809055687680 total_execution_walltime=11.416s REALTIME_MAIN_END_PERF_FORMAT=6999837.860405222 [time_cmd_output] time_elapsed_sec=11.55, faults_major=0, faults_minor=91178, mem_maxresident_kb=366624, user_cputime=558.96, sys_cputime=2.16, percent_cpu=4855% 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