__step=000297 __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_bst_kcas_validate.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000297.txt __path_data=data/data000297.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55da6e222400 addrof(kcasDescriptors[1])=0x55da6e222d00 addrof(rdcssDescriptors[0])=0x55da6e342400 addrof(rdcssDescriptors[1])=0x55da6e3424c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6982353.853956842 binary=./sigouin_int_bst_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_bst_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=0.5 DEL_FRAC=0.5 RQ=0 RQSIZE=0 MAXKEY=200000 PREFILL_THREADS=48 DESIRED_PREFILL_SIZE=-1 TOTAL_THREADS=48 WORK_THREADS=48 RQ_THREADS=0 distribution=0 INS_DEL_FRAC=0.5 0.5 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.005s prefilling_size=99585 prefilling_elapsed_millis=1.001s finished prefilling to size 99570 for expected size 100000 keysum=9958129564, performing 56770336 updates; total_prefilling_elapsed_ms=1102 ms) pref_size=99570 pref_millis=1102 prefill_elapsed_ms=1122 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6982355.121854404 REALTIME_END_PERF_FORMAT=6982365.122004106 ############################################################################### ################################## 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 869 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.031s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 245 459 803 1324 2046 2969 4021 5061 5986 6612 6921 6565 5879 5016 3863 2797 1909 1252 758 422 229 107 47 15 8 2 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 5 18 66 166 321 630 1052 1607 2208 2893 3322 3900 3803 3530 3236 2538 1883 1362 934 573 317 187 82 39 11 7 2 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 127 250 477 869 1490 2367 3599 5073 6668 8194 9505 10243 10465 9682 8546 7099 5335 3792 2614 1692 995 546 294 129 54 19 9 2 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 127 250 477 869 1490 2367 3599 5073 6668 8194 9505 10243 10465 9682 8546 7099 5335 3792 2614 1692 995 546 294 129 54 19 9 2 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.98438 1.9685 1.928 1.85954 1.79056 1.7 1.6561 1.58461 1.52178 1.46986 1.42946 1.38201 1.34599 1.29785 1.27546 1.24374 1.20735 1.1865 1.18592 1.16832 1.14007 1.12462 1.11905 1.07483 1.05426 1.07407 1.05263 1 1 tree_stats_height=35 tree_stats_numInternals=65570 tree_stats_numLeaves=34692 tree_stats_numNodes=100262 tree_stats_numKeys=100262 tree_stats_avgDegreeInternal=1.52908 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.34601 tree_stats_avgKeyDepth=18.597 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:175102 2:139394 4:13754 8:9 [2^00, 2^01]: 175102 (2^01, 2^02]: 139394 (2^02, 2^03]: 13754 (2^03, 2^04]: 9 sum_limbo_reclamation_event_size_total=550978 count_limbo_reclamation_event_size_by_thread=6748 6833 6828 6891 6897 6832 6797 6776 6963 6831 6816 6838 6883 6845 6871 6789 6864 6813 6822 6842 6911 6893 6872 6840 6732 6821 6760 6879 6895 6815 6818 6917 6904 6922 6869 6798 6839 6833 6943 6793 6933 6858 6699 6770 6766 6878 6802 6720 count_limbo_reclamation_event_size_total=328259 sum_limbo_reclamation_event_count_total=1867872 first_thread_announced_epoch_by_thread=83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 83154 sum_num_inserts_by_thread=88586 89224 89346 89520 89799 88508 88825 89443 91120 91437 89859 88789 89369 90098 90069 89756 90813 89523 88500 88463 90101 90383 89402 88738 87872 88781 89888 90253 90385 88369 89343 89329 90092 91154 90268 89254 89193 89158 90291 90614 90679 89856 85732 89452 88812 90883 89269 89686 sum_num_inserts_total=4298284 sum_num_deletes_by_thread=88289 89896 88912 89624 90173 88332 88482 88930 90658 90531 90386 88993 88711 90179 90371 90114 90208 89506 88794 88862 90221 90447 90207 88311 87947 89114 89607 90036 89895 88161 89061 89408 91019 90983 90326 89204 89301 89639 91093 90408 91009 89937 85462 89224 88811 91053 89475 88802 sum_num_deletes_total=4298112 sum_num_searches_by_thread=17521959 17723188 17722076 17747458 17804983 17554866 17478189 17655657 17913712 18011994 17820179 17633972 17624821 17819095 17869424 17862262 17909834 17753213 17584500 17639198 17828503 17935960 17774957 17547399 17502477 17622336 17769277 17829823 17793879 17506454 17636433 17711956 17835158 18002688 17823520 17687295 17628996 17758037 17869928 17893418 17923830 17771250 16933572 17708785 17598594 17953262 17722985 17616822 sum_num_searches_total=850838174 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=17698834 17902308 17900334 17926602 17984955 17731706 17655496 17834030 18095490 18193962 18000424 17811754 17802901 17999372 18049864 18042132 18090855 17932242 17761794 17816523 18008825 18116790 17954566 17724448 17678296 17800231 17948772 18010112 17974159 17682984 17814837 17890693 18016269 18184825 18004114 17865753 17807490 17936834 18051312 18074440 18105518 17951043 17104766 17887461 17776217 18135198 17901729 17795310 average_num_operations_total=17904886 stdev_num_operations_total=26598 sum_num_operations_total=859434570 min_num_operations_total=17104766 max_num_operations_total=18193962 first_time_thread_terminate_by_thread=10000148 10000148 10000148 10000149 10000149 10000148 10000149 10000149 10000148 10000149 10000148 10000149 10000148 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000148 10000149 10000148 10000148 10000148 10000149 10000149 10000148 10000149 10000149 10000148 10000149 10000148 10000149 10000148 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000148 10000149 min_time_thread_terminate_total=10000148 max_time_thread_terminate_total=10000149 first_time_thread_start_by_thread=3 4 3 3 3 4 4 3 3 4 3 3 3 3 3 4 3 4 4 3 3 4 4 4 3 4 3 3 3 4 4 3 3 4 3 3 3 3 3 4 3 4 4 3 3 4 4 4 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=10014067084 threads_final_size=100262 final_keysum=10014067084 final_size=100262 validate_result=success Validation OK. Structural validation OK. total_find=850838174 total_rq=0 total_inserts=4298284 total_deletes=4298112 total_updates=8596396 total_queries=850838174 total_ops=859434570 find_throughput=85083817 rq_throughput=0 update_throughput=859639 query_throughput=85083817 total_throughput=85943457 total find : 850838174 total rq : 0 total inserts : 4298284 total deletes : 4298112 total updates : 8596396 total queries : 850838174 total ops : 859434570 find throughput : 85083817 rq throughput : 0 update throughput : 859639 query throughput : 85083817 total throughput : 85943457 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=6.24907 PAPI_L3_TCM=0.000133633 PAPI_TOT_CYC=1525.15 PAPI_TOT_INS=1061.33 garbage=42483721196351 total_execution_walltime=11.404s REALTIME_MAIN_END_PERF_FORMAT=6982365.258609893 [time_cmd_output] time_elapsed_sec=11.53, faults_major=0, faults_minor=76794, mem_maxresident_kb=309400, user_cputime=559.67, sys_cputime=2.94, percent_cpu=4876% timeout=false algorithm=sigouin_int_bst_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