__step=000138 __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" ./brown_sigouin_abtree_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=data000138.txt __path_data=data/data000138.txt __hostname=jax sizeof(kcasdesc_t)=1920 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x560ecec27480 addrof(kcasDescriptors[1])=0x560ecec27c00 addrof(rdcssDescriptors[0])=0x560eced17480 addrof(rdcssDescriptors[1])=0x560eced17540 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6996037.641045685 binary=./brown_sigouin_abtree_kcas_validate.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=brown_sigouin_abtree_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=40 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.005s prefilling_size=8604327 prefilling_elapsed_millis=1.001s prefilling_size=8811322 prefilling_elapsed_millis=1.101s prefilling_size=8985764 prefilling_elapsed_millis=1.201s prefilling_size=9133435 prefilling_elapsed_millis=1.301s prefilling_size=9258758 prefilling_elapsed_millis=1.402s prefilling_size=9365227 prefilling_elapsed_millis=1.502s prefilling_size=9453344 prefilling_elapsed_millis=1.602s prefilling_size=9528116 prefilling_elapsed_millis=1.702s prefilling_size=9592150 prefilling_elapsed_millis=1.802s prefilling_size=9649820 prefilling_elapsed_millis=1.903s prefilling_size=9698949 prefilling_elapsed_millis=2.003s prefilling_size=9737398 prefilling_elapsed_millis=2.103s prefilling_size=9772845 prefilling_elapsed_millis=2.203s prefilling_size=9803221 prefilling_elapsed_millis=2.303s finished prefilling to size 9803269 for expected size 10000000 keysum=98007362525864, performing 98777709 updates; total_prefilling_elapsed_ms=2405 ms) pref_size=9803269 pref_millis=2405 prefill_elapsed_ms=2431 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6996040.241889354 REALTIME_END_PERF_FORMAT=6996050.242040217 ############################################################################### ################################## 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 3816 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.051s tree_stats_numInternalsAtDepth=1 1 8 70 508 3816 28685 223049 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1756166 tree_stats_numNodesAtDepth=1 1 8 70 508 3816 28685 223049 1756166 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9960112 tree_stats_avgDegreeAtDepth=1 8 8.75 7.25714 7.51181 7.51703 7.77581 7.87345 5.67151 tree_stats_height=9 tree_stats_numInternals=256138 tree_stats_numLeaves=1756166 tree_stats_numNodes=2012304 tree_stats_numKeys=9960112 tree_stats_avgDegreeInternal=7.85633 tree_stats_avgDegreeLeaves=5.67151 tree_stats_avgDegree=5.94961 tree_stats_avgKeyDepth=8 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:49064 2:54409 4:8609 8:277 16:3 [2^00, 2^01]: 49064 (2^01, 2^02]: 54409 (2^02, 2^03]: 8609 (2^03, 2^04]: 277 (2^04, 2^05]: 3 sum_limbo_reclamation_event_size_total=203619 count_limbo_reclamation_event_size_by_thread=2286 2556 2214 2286 2214 2292 2518 2292 2300 2298 2136 2228 2438 2514 2666 2590 2244 2408 2226 2212 2244 2206 2330 2446 2164 2516 2642 2234 2116 2262 2550 2220 2222 2264 2256 2170 2158 2536 2168 2208 2296 2540 2320 2548 2220 2644 2346 2618 count_limbo_reclamation_event_size_total=112362 sum_limbo_reclamation_event_count_total=2037024 first_thread_announced_epoch_by_thread=51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 51560 sum_commit_tx_reader_total= sum_commit_tx_reader_by_thread= sum_commit_tx_writer_total= sum_commit_tx_writer_by_thread= sum_abort_tx_total= sum_abort_tx_by_thread= max_ht_capacity_by_thread= linear_histogram_of_none_tx_rdset_size_full_data= linear_histogram_of_none_tx_wrset_size_full_data= sum_fasthtm_commit_total= sum_fasthtm_abort_total= sum_htmpostfix_commit_total= sum_htmpostfix_abort_total= sum_htmprefix_commit_total= sum_htmprefix_abort_total= sum_htmprefix_abort_explicit_total= sum_htmprefix_abort_conflict_total= sum_htmprefix_abort_capacity_total= sum_htmprefix_abort_nesting_total= sum_htmprefix_abort_illegal_total= sum_htmprefix_abort_zero_total= sum_stmprefix_abort_total= sum_stmprefix_commit_total= sum_slow_commit_total= sum_abtree_insert_repeat_total=79 sum_abtree_erase_repeat_total=8 sum_abtree_search_repeat_total= sum_abtree_search_complete_total=47280957 sum_abtree_fix_weight_viol_attempt_total=161151 sum_abtree_fix_degree_viol_attempt_total=124539 sum_abtree_rebalancing_kcas_attempt_total=285686 sum_abtree_update_kcas_attempt_total=23503786 sum_abtree_internal_complex_search_while_iters_total=47280963 sum_abtree_internal_basic_search_while_iters_total=422984696 sum_kcas_desc_new_total= sum_num_inserts_by_thread=485581 488884 492007 493137 489742 484794 487254 488836 493592 495142 488968 487589 489057 488661 493176 492703 490077 486466 485944 488294 492542 493118 487547 485760 486278 489464 490779 491387 491233 486119 488159 489432 493831 494023 488900 486250 487629 491227 494168 493172 491888 486681 484878 485747 490899 491750 489306 483476 sum_num_inserts_total=23495547 sum_num_deletes_by_thread=487089 489263 493025 492078 490109 486156 485875 489345 494098 494012 489449 487604 488863 490000 493587 493985 490709 486376 486305 487685 491398 493586 488717 483441 485642 489091 491768 491246 491402 485265 488613 488021 493134 493735 489212 488452 489763 490259 494399 493068 490181 487489 485570 486858 489946 491585 488947 483193 sum_num_deletes_total=23499594 sum_num_searches_by_thread=8756809 8804132 8863190 8853926 8832449 8734920 8765982 8795610 8888448 8905402 8806971 8765083 8791458 8823277 8887034 8868150 8815807 8763987 8741713 8790912 8840579 8879451 8799671 8721918 8750176 8800088 8845103 8844536 8838587 8737235 8770474 8811006 8898287 8896077 8810254 8765929 8802158 8823190 8897433 8896358 8827242 8760400 8740744 8765947 8832922 8849813 8802417 8720770 sum_num_searches_total=422984025 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=9729479 9782279 9848222 9839141 9812300 9705870 9739111 9773791 9876138 9894556 9785388 9740276 9769378 9801938 9873797 9854838 9796593 9736829 9713962 9766891 9824519 9866155 9775935 9691119 9722096 9778643 9827650 9827169 9821222 9708619 9747246 9788459 9885252 9883835 9788366 9740631 9779550 9804676 9886000 9882598 9809311 9734570 9711192 9738552 9813767 9833148 9780670 9687439 average_num_operations_total=9791232 stdev_num_operations_total=8494 sum_num_operations_total=469979166 min_num_operations_total=9687439 max_num_operations_total=9894556 first_time_thread_terminate_by_thread=10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 10000151 min_time_thread_terminate_total=10000151 max_time_thread_terminate_total=10000151 first_time_thread_start_by_thread=3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 3 3 3 3 3 3 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=99592513645882 threads_final_size=9960112 final_keysum=99592513645882 final_size=9960112 validate_result=success Validation OK. Structural validation OK. total_find=422984025 total_rq=0 total_inserts=23495547 total_deletes=23499594 total_updates=46995141 total_queries=422984025 total_ops=469979166 find_throughput=42298402 rq_throughput=0 update_throughput=4699514 query_throughput=42298402 total_throughput=46997916 total find : 422984025 total rq : 0 total inserts : 23495547 total deletes : 23499594 total updates : 46995141 total queries : 422984025 total ops : 469979166 find throughput : 42298402 rq throughput : 0 update throughput : 4699514 query throughput : 42298402 total throughput : 46997916 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=18.4382 PAPI_L3_TCM=6.3757 PAPI_TOT_CYC=2835.43 PAPI_TOT_INS=962.582 garbage=2056478496237880 total_execution_walltime=12.75s REALTIME_MAIN_END_PERF_FORMAT=6996050.391571780 [time_cmd_output] time_elapsed_sec=12.91, faults_major=0, faults_minor=185744, mem_maxresident_kb=743496, user_cputime=628.79, sys_cputime=3.23, percent_cpu=4893% timeout=false algorithm=brown_sigouin_abtree_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=2