__step=000777 __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 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000777.txt __path_data=data/data000777.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x56372840a400 addrof(kcasDescriptors[1])=0x56372840ad00 addrof(rdcssDescriptors[0])=0x56372852a400 addrof(rdcssDescriptors[1])=0x56372852a4c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6988348.192137058 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=50 DEL_FRAC=50 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=50.0 50.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.026s prefilling_size=6383179 prefilling_elapsed_millis=1.001s prefilling_size=6698628 prefilling_elapsed_millis=1.101s prefilling_size=6983672 prefilling_elapsed_millis=1.201s prefilling_size=7244244 prefilling_elapsed_millis=1.301s prefilling_size=7480560 prefilling_elapsed_millis=1.401s prefilling_size=7695573 prefilling_elapsed_millis=1.502s prefilling_size=7891795 prefilling_elapsed_millis=1.602s prefilling_size=8071544 prefilling_elapsed_millis=1.702s prefilling_size=8235066 prefilling_elapsed_millis=1.802s prefilling_size=8386218 prefilling_elapsed_millis=1.902s prefilling_size=8524089 prefilling_elapsed_millis=2.003s prefilling_size=8649165 prefilling_elapsed_millis=2.103s prefilling_size=8763319 prefilling_elapsed_millis=2.203s prefilling_size=8867095 prefilling_elapsed_millis=2.303s prefilling_size=8962195 prefilling_elapsed_millis=2.403s prefilling_size=9049841 prefilling_elapsed_millis=2.504s prefilling_size=9129601 prefilling_elapsed_millis=2.604s prefilling_size=9203144 prefilling_elapsed_millis=2.704s prefilling_size=9270169 prefilling_elapsed_millis=2.804s prefilling_size=9329584 prefilling_elapsed_millis=2.904s prefilling_size=9386166 prefilling_elapsed_millis=3.005s prefilling_size=9437828 prefilling_elapsed_millis=3.105s prefilling_size=9483279 prefilling_elapsed_millis=3.205s prefilling_size=9527037 prefilling_elapsed_millis=3.305s prefilling_size=9565471 prefilling_elapsed_millis=3.405s prefilling_size=9602591 prefilling_elapsed_millis=3.506s prefilling_size=9635540 prefilling_elapsed_millis=3.606s prefilling_size=9665358 prefilling_elapsed_millis=3.706s prefilling_size=9693005 prefilling_elapsed_millis=3.806s prefilling_size=9717802 prefilling_elapsed_millis=3.906s prefilling_size=9742399 prefilling_elapsed_millis=4.007s prefilling_size=9763776 prefilling_elapsed_millis=4.107s prefilling_size=9784419 prefilling_elapsed_millis=4.207s prefilling_size=9802235 prefilling_elapsed_millis=4.307s finished prefilling to size 9802231 for expected size 10000000 keysum=98020881946183, performing 78706743 updates; total_prefilling_elapsed_ms=4408 ms) pref_size=9802231 pref_millis=4408 prefill_elapsed_ms=4431 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6988352.838101436 REALTIME_END_PERF_FORMAT=6988362.838270002 ############################################################################### ################################## 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 65536 131072 262128 521796 986803 1497638 1416545 664271 125415 6734 25 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 16 2282 49737 393919 1228485 1586469 830894 160073 8361 29 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524078 1036540 1891557 2645030 2250740 956309 166807 8386 29 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524078 1036540 1891557 2645030 2250740 956309 166807 8386 29 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99926 1.98219 1.87286 1.60659 1.31538 1.12975 1.04328 1.0099 1.00048 1 tree_stats_height=28 tree_stats_numInternals=5743498 tree_stats_numLeaves=4260265 tree_stats_numNodes=10003763 tree_stats_numKeys=10003763 tree_stats_avgDegreeInternal=1.74175 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42587 tree_stats_avgKeyDepth=21.654 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:0 16:0 32:45 64:367835 128:16840 (2^05, 2^06]: 45 (2^06, 2^07]: 367835 (2^07, 2^08]: 16840 sum_limbo_reclamation_event_size_total=42975507 count_limbo_reclamation_event_size_by_thread=8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 8015 count_limbo_reclamation_event_size_total=384720 sum_limbo_reclamation_event_count_total=384816 first_thread_announced_epoch_by_thread=23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 23338 sum_num_inserts_by_thread=1787741 1792585 1802779 1802489 1799055 1785438 1789719 1794738 1807598 1810719 1797084 1784811 1790156 1798728 1805950 1805028 1797841 1787978 1782305 1790768 1800442 1803773 1792379 1782976 1788458 1789888 1803733 1801889 1797365 1786132 1791906 1795406 1810000 1811630 1795648 1786965 1792749 1801472 1808300 1806048 1794876 1792698 1783548 1790602 1787603 1806018 1791443 1781812 sum_num_inserts_total=86189269 sum_num_deletes_by_thread=1786345 1793011 1805793 1807152 1799081 1785016 1788373 1793361 1808895 1809905 1796216 1789365 1792828 1797704 1807324 1804426 1797213 1790766 1782977 1792624 1801921 1805527 1791788 1778572 1786129 1791668 1800991 1803032 1798070 1786876 1792161 1792385 1809849 1811852 1795800 1788546 1790913 1800398 1805914 1809243 1797766 1791327 1784378 1791409 1788434 1805338 1790971 1783761 sum_num_deletes_total=86203394 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3574086 3585596 3608572 3609641 3598136 3570454 3578092 3588099 3616493 3620624 3593300 3574176 3582984 3596432 3613274 3609454 3595054 3578744 3565282 3583392 3602363 3609300 3584167 3561548 3574587 3581556 3604724 3604921 3595435 3573008 3584067 3587791 3619849 3623482 3591448 3575511 3583662 3601870 3614214 3615291 3592642 3584025 3567926 3582011 3576037 3611356 3582414 3565573 average_num_operations_total=3591513 stdev_num_operations_total=2445 sum_num_operations_total=172392663 min_num_operations_total=3561548 max_num_operations_total=3623482 first_time_thread_terminate_by_thread=10000168 10000170 10000167 10000168 10000168 10000168 10000168 10000170 10000168 10000168 10000168 10000168 10000167 10000167 10000168 10000168 10000171 10000168 10000168 10000168 10000167 10000172 10000167 10000169 10000167 10000170 10000167 10000168 10000168 10000168 10000168 10000168 10000168 10000168 10000168 10000168 10000167 10000167 10000168 10000171 10000168 10000167 10000168 10000169 10000168 10000169 10000167 10000168 min_time_thread_terminate_total=10000167 max_time_thread_terminate_total=10000172 first_time_thread_start_by_thread=3 4 3 3 3 4 4 4 3 4 4 3 4 3 4 3 4 4 4 4 4 4 3 3 3 4 3 3 3 4 4 4 3 4 4 3 4 3 4 3 4 4 4 4 4 4 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=100035942395022 threads_final_size=10003763 final_keysum=100035942395022 final_size=10003763 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=86189269 total_deletes=86203394 total_updates=172392663 total_queries=0 total_ops=172392663 find_throughput=0 rq_throughput=0 update_throughput=17239266 query_throughput=0 total_throughput=17239266 total find : 0 total rq : 0 total inserts : 86189269 total deletes : 86203394 total updates : 172392663 total queries : 0 total ops : 172392663 find throughput : 0 rq throughput : 0 update throughput : 17239266 query throughput : 0 total throughput : 17239266 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=28.3069 PAPI_L3_TCM=10.6432 PAPI_TOT_CYC=7761.14 PAPI_TOT_INS=3160.06 garbage=0 total_execution_walltime=14.78s REALTIME_MAIN_END_PERF_FORMAT=6988362.972683196 [time_cmd_output] time_elapsed_sec=14.95, faults_major=0, faults_minor=237360, mem_maxresident_kb=951384, user_cputime=722.24, sys_cputime=2.85, percent_cpu=4847% 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