__step=000202 __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 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 __file_data=data000202.txt __path_data=data/data000202.txt __hostname=jax sizeof(kcasdesc_t)=2304 sizeof(rdcssdesc_t)=192 addrof(kcasDescriptors[0])=0x55fb55719400 addrof(kcasDescriptors[1])=0x55fb55719d00 addrof(rdcssDescriptors[0])=0x55fb55839400 addrof(rdcssDescriptors[1])=0x55fb558394c0 sizeof(validationSet)=1608 REALTIME_MAIN_START_PERF_FORMAT=6996860.682476523 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.018s prefilling_size=6359673 prefilling_elapsed_millis=1.001s prefilling_size=6670708 prefilling_elapsed_millis=1.101s prefilling_size=6954515 prefilling_elapsed_millis=1.201s prefilling_size=7213466 prefilling_elapsed_millis=1.302s prefilling_size=7448866 prefilling_elapsed_millis=1.402s prefilling_size=7662429 prefilling_elapsed_millis=1.502s prefilling_size=7859003 prefilling_elapsed_millis=1.602s prefilling_size=8038351 prefilling_elapsed_millis=1.702s prefilling_size=8201857 prefilling_elapsed_millis=1.803s prefilling_size=8350318 prefilling_elapsed_millis=1.903s prefilling_size=8485548 prefilling_elapsed_millis=2.003s prefilling_size=8610775 prefilling_elapsed_millis=2.103s prefilling_size=8724068 prefilling_elapsed_millis=2.203s prefilling_size=8829825 prefilling_elapsed_millis=2.303s prefilling_size=8925933 prefilling_elapsed_millis=2.404s prefilling_size=9014193 prefilling_elapsed_millis=2.504s prefilling_size=9093174 prefilling_elapsed_millis=2.604s prefilling_size=9167321 prefilling_elapsed_millis=2.704s prefilling_size=9233355 prefilling_elapsed_millis=2.804s prefilling_size=9295552 prefilling_elapsed_millis=2.905s prefilling_size=9352564 prefilling_elapsed_millis=3.005s prefilling_size=9404237 prefilling_elapsed_millis=3.105s prefilling_size=9451057 prefilling_elapsed_millis=3.205s prefilling_size=9495237 prefilling_elapsed_millis=3.305s prefilling_size=9534916 prefilling_elapsed_millis=3.406s prefilling_size=9573683 prefilling_elapsed_millis=3.506s prefilling_size=9608634 prefilling_elapsed_millis=3.606s prefilling_size=9639833 prefilling_elapsed_millis=3.706s prefilling_size=9667503 prefilling_elapsed_millis=3.806s prefilling_size=9693512 prefilling_elapsed_millis=3.907s prefilling_size=9716933 prefilling_elapsed_millis=4.007s prefilling_size=9738709 prefilling_elapsed_millis=4.107s prefilling_size=9757957 prefilling_elapsed_millis=4.207s prefilling_size=9777934 prefilling_elapsed_millis=4.307s prefilling_size=9795074 prefilling_elapsed_millis=4.408s prefilling_size=9811168 prefilling_elapsed_millis=4.508s finished prefilling to size 9811144 for expected size 10000000 keysum=98092562961860, performing 82137103 updates; total_prefilling_elapsed_ms=4609 ms) pref_size=9811144 pref_millis=4609 prefill_elapsed_ms=4632 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6996865.522200604 REALTIME_END_PERF_FORMAT=6996875.522351201 ############################################################################### ################################## 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.047s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131070 261963 517978 958406 1431460 1390617 716400 158002 11546 115 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 161 5335 66311 396690 1143594 1517675 887712 201490 14536 148 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262124 523313 1024717 1828150 2534211 2234075 1045714 213036 14651 148 tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262124 523313 1024717 1828150 2534211 2234075 1045714 213036 14651 148 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99986 1.99705 1.96833 1.84877 1.60321 1.33283 1.14741 1.05263 1.01458 1.00225 1 tree_stats_height=28 tree_stats_numInternals=5708628 tree_stats_numLeaves=4233654 tree_stats_numNodes=9942282 tree_stats_numKeys=9942282 tree_stats_avgDegreeInternal=1.74162 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.42582 tree_stats_avgKeyDepth=21.6937 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:47 2:1512 4:54651 8:374426 16:49259 32:1 [2^00, 2^01]: 47 (2^01, 2^02]: 1512 (2^02, 2^03]: 54651 (2^03, 2^04]: 374426 (2^04, 2^05]: 49259 (2^05, 2^06]: 1 sum_limbo_reclamation_event_size_total=5424084 count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9997 9998 9998 9998 9997 9997 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9997 9997 9998 9998 9998 9997 count_limbo_reclamation_event_size_total=479896 sum_limbo_reclamation_event_count_total=547536 first_thread_announced_epoch_by_thread=30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 30436 sum_num_inserts_by_thread=258475 260671 261256 262721 262691 258937 260981 261173 261911 264270 259747 258075 261752 261328 261741 263523 260910 260552 257105 262064 261424 262116 261162 257174 258679 261438 262862 261753 262380 258260 260899 262455 262494 263160 260293 258293 259381 262736 264050 261751 262436 259628 258601 259153 262573 262016 259331 257313 sum_num_inserts_total=12523694 sum_num_deletes_by_thread=258485 260870 260683 261502 261898 259433 261052 261817 263379 263992 259537 259040 260794 260618 261256 263259 261305 260454 257864 261351 260143 262050 260370 257653 258978 260878 261451 261187 261240 260024 260318 261110 262638 261719 259965 258192 260086 261905 263891 263016 261512 258434 258709 258166 261469 261691 259398 257933 sum_num_deletes_total=12512715 sum_num_searches_by_thread=4648770 4701636 4699060 4705214 4709915 4668929 4687702 4702842 4720468 4751093 4674114 4650821 4697255 4690261 4717935 4741337 4695154 4684675 4642211 4693018 4688170 4712971 4685588 4634217 4660096 4703162 4704840 4704982 4720344 4674672 4690857 4707482 4726249 4728010 4672284 4647485 4670072 4723966 4743464 4720066 4721150 4667130 4667502 4664717 4721257 4711514 4662506 4633826 sum_num_searches_total=225250989 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=5165730 5223177 5220999 5229437 5234504 5187299 5209735 5225832 5245758 5279355 5193398 5167936 5219801 5212207 5240932 5268119 5217369 5205681 5157180 5216433 5209737 5237137 5207120 5149044 5177753 5225478 5229153 5227922 5243964 5192956 5212074 5231047 5251381 5252889 5192542 5163970 5189539 5248607 5271405 5244833 5245098 5185192 5184812 5182036 5245299 5235221 5181235 5149072 average_num_operations_total=5214320 stdev_num_operations_total=4731 sum_num_operations_total=250287398 min_num_operations_total=5149044 max_num_operations_total=5279355 first_time_thread_terminate_by_thread=10000150 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000150 10000149 10000149 10000149 10000149 10000149 10000149 10000150 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000149 10000150 10000149 min_time_thread_terminate_total=10000149 max_time_thread_terminate_total=10000150 first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 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=3 max_time_thread_start_total=3 sum_duration_all_ops_total= threads_final_keysum=99410103372809 threads_final_size=9942282 final_keysum=99410103372809 final_size=9942282 validate_result=success Validation OK. Structural validation OK. total_find=225250989 total_rq=0 total_inserts=12523694 total_deletes=12512715 total_updates=25036409 total_queries=225250989 total_ops=250287398 find_throughput=22525098 rq_throughput=0 update_throughput=2503640 query_throughput=22525098 total_throughput=25028739 total find : 225250989 total rq : 0 total inserts : 12523694 total deletes : 12512715 total updates : 25036409 total queries : 225250989 total ops : 250287398 find throughput : 22525098 rq throughput : 0 update throughput : 2503640 query throughput : 22525098 total throughput : 25028739 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=27.4574 PAPI_L3_TCM=9.43455 PAPI_TOT_CYC=5346.53 PAPI_TOT_INS=1381.99 garbage=1114506577062984 total_execution_walltime=14.982s REALTIME_MAIN_END_PERF_FORMAT=6996875.671479691 [time_cmd_output] time_elapsed_sec=15.16, faults_major=0, faults_minor=234886, mem_maxresident_kb=941392, user_cputime=731.58, sys_cputime=2.96, percent_cpu=4843% 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