__step=000442 __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 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=data000442.txt __path_data=data/data000442.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x564d0bf11400 addrof(kcasDescriptors[1])=0x564d0bf11d00 addrof(rdcssDescriptors[0])=0x564d0c031400 addrof(rdcssDescriptors[1])=0x564d0c0314c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6984233.512977444 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=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=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=6189374 prefilling_elapsed_millis=1.001s prefilling_size=6486693 prefilling_elapsed_millis=1.101s prefilling_size=6754742 prefilling_elapsed_millis=1.201s prefilling_size=6999937 prefilling_elapsed_millis=1.301s prefilling_size=7224926 prefilling_elapsed_millis=1.401s prefilling_size=7427987 prefilling_elapsed_millis=1.502s prefilling_size=7614065 prefilling_elapsed_millis=1.602s prefilling_size=7787221 prefilling_elapsed_millis=1.702s prefilling_size=7944350 prefilling_elapsed_millis=1.802s prefilling_size=8086641 prefilling_elapsed_millis=1.903s prefilling_size=8220029 prefilling_elapsed_millis=2.003s prefilling_size=8343259 prefilling_elapsed_millis=2.103s prefilling_size=8456042 prefilling_elapsed_millis=2.203s prefilling_size=8560869 prefilling_elapsed_millis=2.303s prefilling_size=8659625 prefilling_elapsed_millis=2.404s prefilling_size=8749247 prefilling_elapsed_millis=2.504s prefilling_size=8831170 prefilling_elapsed_millis=2.604s prefilling_size=8908109 prefilling_elapsed_millis=2.704s prefilling_size=8978393 prefilling_elapsed_millis=2.804s prefilling_size=9044961 prefilling_elapsed_millis=2.905s prefilling_size=9107061 prefilling_elapsed_millis=3.005s prefilling_size=9164004 prefilling_elapsed_millis=3.105s prefilling_size=9218257 prefilling_elapsed_millis=3.205s prefilling_size=9267711 prefilling_elapsed_millis=3.305s prefilling_size=9313974 prefilling_elapsed_millis=3.406s prefilling_size=9356579 prefilling_elapsed_millis=3.506s prefilling_size=9395903 prefilling_elapsed_millis=3.606s prefilling_size=9433487 prefilling_elapsed_millis=3.706s prefilling_size=9468319 prefilling_elapsed_millis=3.806s prefilling_size=9501524 prefilling_elapsed_millis=3.907s prefilling_size=9530421 prefilling_elapsed_millis=4.007s prefilling_size=9558845 prefilling_elapsed_millis=4.107s prefilling_size=9586566 prefilling_elapsed_millis=4.207s prefilling_size=9611520 prefilling_elapsed_millis=4.307s prefilling_size=9633707 prefilling_elapsed_millis=4.408s prefilling_size=9654577 prefilling_elapsed_millis=4.508s prefilling_size=9674467 prefilling_elapsed_millis=4.608s prefilling_size=9695966 prefilling_elapsed_millis=4.708s prefilling_size=9713094 prefilling_elapsed_millis=4.808s prefilling_size=9730511 prefilling_elapsed_millis=4.909s prefilling_size=9745721 prefilling_elapsed_millis=5.009s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 254292 keys with 48 threads... tid=0 thread_prefill_amount=295786 percent_done_estimate=5583.2 elapsed_s=5 est_remaining_s=-11 / -6 tid=0 thread_prefill_amount=299952 percent_done_estimate=5661.9 elapsed_s=5 est_remaining_s=-11 / -6 timing_elapsed 0.0394382s prefilling_size=10000000 prefilling_elapsed_millis=0.039s finished prefilling to size 10000000 for expected size 10000000 keysum=100008820508801, performing 91712660 updates; total_prefilling_elapsed_ms=39 ms) pref_size=10000000 pref_millis=39 prefill_elapsed_ms=39 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6984238.828160695 REALTIME_END_PERF_FORMAT=6984248.828242681 ############################################################################### ################################## TIME IS UP ################################# ############################################################################### joining threads... ############################################################################### ################################# END RUNNING ################################# ############################################################################### 10.1s PRODUCING OUTPUT computing tree_stats in PARALLEL... bounded depth BFS to partition into subtrees for parallel computation (48 threads)... partitioned into 256 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.069s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262116 521854 986448 1492818 1412593 674004 133171 8192 90 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 28 2181 50290 396937 1222776 1573995 839865 169859 10325 110 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524035 1036738 1889755 2635369 2247999 973036 178051 10415 110 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524035 1036738 1889755 2635369 2247999 973036 178051 10415 110 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99914 1.98254 1.8713 1.6046 1.317 1.13302 1.04612 1.01249 1.00192 1 tree_stats_height=28 tree_stats_numInternals=5753429 tree_stats_numLeaves=4266366 tree_stats_numNodes=10019795 tree_stats_numKeys=10019795 tree_stats_avgDegreeInternal=1.74153 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42579 tree_stats_avgKeyDepth=21.662 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:45 2:1427 4:51988 8:374003 16:52392 [2^00, 2^01]: 45 (2^01, 2^02]: 1427 (2^02, 2^03]: 51988 (2^03, 2^04]: 374003 (2^04, 2^05]: 52392 sum_limbo_reclamation_event_size_total=5472673 count_limbo_reclamation_event_size_by_thread=9997 9997 9997 9997 9997 9996 9998 9997 9997 9997 9997 9997 9996 9997 9997 9997 9997 9997 9997 9997 9997 9997 9996 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9998 9997 9997 9997 9998 9997 9997 9997 9997 9997 9997 9997 9996 9997 9997 count_limbo_reclamation_event_size_total=479855 sum_limbo_reclamation_event_count_total=542925 first_thread_announced_epoch_by_thread=31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 sum_num_inserts_by_thread=257135 257454 260126 259392 259240 256829 256995 258410 260946 260897 258712 258340 258453 259095 261059 260907 258182 256870 255101 257958 259535 259653 257298 255489 257365 258676 260698 260325 258898 256836 256867 258859 260159 261204 257956 256708 258458 259749 258425 260054 258684 257437 256705 257538 258711 259568 256674 255922 sum_num_inserts_total=12406552 sum_num_deletes_by_thread=256968 257625 259792 259878 258689 256745 255888 258138 261701 259971 258565 256802 258749 259659 261112 260299 259219 258470 255757 257607 259400 260360 257125 254956 257492 257783 260414 260278 258657 257169 258345 258824 261743 260903 257796 257466 257040 259237 259753 260552 258628 258289 255903 256266 258391 259642 257079 255742 sum_num_deletes_total=12406867 sum_num_searches_by_thread=4628807 4641259 4688015 4668606 4665737 4617884 4627753 4646312 4701425 4696865 4648885 4624140 4648285 4670234 4691949 4694161 4656233 4634391 4598985 4641986 4659776 4687902 4628836 4600625 4624503 4635162 4685571 4679951 4656161 4620289 4640337 4657612 4700109 4704680 4641566 4622893 4647221 4673272 4679845 4684306 4662913 4639158 4615771 4628979 4660307 4674822 4628067 4597998 sum_num_searches_total=223330544 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=5142910 5156338 5207933 5187876 5183666 5131458 5140636 5162860 5224072 5217733 5166162 5139282 5165487 5188988 5214120 5215367 5173634 5149731 5109843 5157551 5178711 5207915 5143259 5111070 5139360 5151621 5206683 5200554 5173716 5134294 5155549 5175295 5222011 5226787 5157318 5137067 5162719 5192258 5198023 5204912 5180225 5154884 5128379 5142783 5177409 5194032 5141820 5109662 average_num_operations_total=5169665 stdev_num_operations_total=4680 sum_num_operations_total=248143963 min_num_operations_total=5109662 max_num_operations_total=5226787 first_time_thread_terminate_by_thread=10000140 10000081 10000081 10000081 10000081 10000084 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000082 10000083 10000081 10000081 10000081 10000081 10000082 10000081 10000081 10000081 10000082 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000082 10000082 10000082 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 min_time_thread_terminate_total=10000081 max_time_thread_terminate_total=10000140 first_time_thread_start_by_thread=9 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 min_time_thread_start_total=4 max_time_thread_start_total=9 sum_duration_all_ops_total= threads_final_keysum=100180306940587 threads_final_size=10019795 final_keysum=100180306940587 final_size=10019795 validate_result=success Validation OK. Structural validation OK. total_find=223330544 total_rq=0 total_inserts=12406552 total_deletes=12406867 total_updates=24813419 total_queries=223330544 total_ops=248143963 find_throughput=22333054 rq_throughput=0 update_throughput=2481341 query_throughput=22333054 total_throughput=24814396 total find : 223330544 total rq : 0 total inserts : 12406552 total deletes : 12406867 total updates : 24813419 total queries : 223330544 total ops : 248143963 find throughput : 22333054 rq throughput : 0 update throughput : 2481341 query throughput : 22333054 total throughput : 24814396 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=27.5945 PAPI_L3_TCM=9.46982 PAPI_TOT_CYC=5393.99 PAPI_TOT_INS=1378.68 garbage=1115347735254058 total_execution_walltime=15.552s REALTIME_MAIN_END_PERF_FORMAT=6984249.65554715 [time_cmd_output] time_elapsed_sec=15.72, faults_major=0, faults_minor=235588, mem_maxresident_kb=943564, user_cputime=738.24, sys_cputime=3.74, percent_cpu=4719% 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=2