__step=000714 __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" ./guerraoui_ext_bst_ticket.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=data000714.txt __path_data=data/data000714.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6987503.572132983 binary=./guerraoui_ext_bst_ticket.debra parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 DS_TYPENAME=guerraoui_ext_bst_ticket 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=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.018s prefilling_size=6714177 prefilling_elapsed_millis=1.001s prefilling_size=7002489 prefilling_elapsed_millis=1.101s prefilling_size=7264293 prefilling_elapsed_millis=1.201s prefilling_size=7498987 prefilling_elapsed_millis=1.302s prefilling_size=7713268 prefilling_elapsed_millis=1.402s prefilling_size=7908318 prefilling_elapsed_millis=1.502s prefilling_size=8085436 prefilling_elapsed_millis=1.602s prefilling_size=8246932 prefilling_elapsed_millis=1.702s prefilling_size=8394870 prefilling_elapsed_millis=1.803s prefilling_size=8530360 prefilling_elapsed_millis=1.903s prefilling_size=8653399 prefilling_elapsed_millis=2.003s prefilling_size=8764898 prefilling_elapsed_millis=2.103s prefilling_size=8865231 prefilling_elapsed_millis=2.203s prefilling_size=8959712 prefilling_elapsed_millis=2.304s prefilling_size=9045212 prefilling_elapsed_millis=2.404s prefilling_size=9121041 prefilling_elapsed_millis=2.504s prefilling_size=9192565 prefilling_elapsed_millis=2.604s prefilling_size=9257852 prefilling_elapsed_millis=2.705s prefilling_size=9318366 prefilling_elapsed_millis=2.805s prefilling_size=9373263 prefilling_elapsed_millis=2.905s prefilling_size=9423540 prefilling_elapsed_millis=3.005s prefilling_size=9468456 prefilling_elapsed_millis=3.105s prefilling_size=9510739 prefilling_elapsed_millis=3.206s prefilling_size=9549817 prefilling_elapsed_millis=3.306s prefilling_size=9586217 prefilling_elapsed_millis=3.406s prefilling_size=9620394 prefilling_elapsed_millis=3.506s prefilling_size=9651439 prefilling_elapsed_millis=3.606s prefilling_size=9678851 prefilling_elapsed_millis=3.707s prefilling_size=9704831 prefilling_elapsed_millis=3.807s prefilling_size=9728531 prefilling_elapsed_millis=3.907s prefilling_size=9750665 prefilling_elapsed_millis=4.007s prefilling_size=9770955 prefilling_elapsed_millis=4.107s prefilling_size=9789707 prefilling_elapsed_millis=4.208s prefilling_size=9806035 prefilling_elapsed_millis=4.308s finished prefilling to size 9806047 for expected size 10000000 keysum=98059797283844, performing 78784577 updates; total_prefilling_elapsed_ms=4409 ms) pref_size=9806047 pref_millis=4409 prefill_elapsed_ms=4426 6node_tIxPvE_object_size=40 6node_tIxPvE_allocated_count=0 6node_tIxPvE_allocated_size=0MB 6node_tIxPvE_get_from_pool=0 6node_tIxPvE_deallocated=0 6node_tIxPvE_limbo_count=0 6node_tIxPvE_limbo_details=0 0 0 global_epoch_counter=3606 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6987508.177781483 REALTIME_END_PERF_FORMAT=6987518.177913598 ############################################################################### ################################## 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.217s tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1019 1999 3867 7325 13463 24009 41296 67860 106433 158632 224920 305106 396032 491632 584014 664361 724866 761176 768656 747956 702267 637301 558263 475010 391067 311493 240824 180683 131254 93412 64906 43670 28185 17589 10531 6101 3453 1897 980 532 277 163 78 34 12 8 4 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 5 39 131 409 1187 2917 6722 14732 29287 54234 92344 144734 214180 300432 399250 503667 603856 688556 753696 789356 793645 767233 716339 641516 558953 470641 382162 300965 230112 169096 121918 86142 59155 38781 24647 14961 8749 5009 2814 1428 787 391 248 122 56 16 12 8 tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2038 3998 7734 14650 26926 48018 82592 135720 212866 317264 449840 610212 792064 983264 1168028 1328722 1449732 1522352 1537312 1495912 1404534 1274602 1116526 950020 782134 622986 481648 361366 262508 186824 129812 87340 56370 35178 21062 12202 6906 3794 1960 1064 554 326 156 68 24 16 8 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 5 39 131 409 1187 2917 6722 14732 29286 54234 92344 144734 214180 300432 399250 503667 603856 688556 753696 789356 793645 767233 716339 641516 558953 470641 382162 300965 230112 169096 121918 86142 59155 38781 24647 14961 8749 5009 2814 1428 787 391 248 122 56 16 12 8 tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 1.99512 1.98086 1.96723 1.94712 1.91898 1.89167 1.86001 1.82163 1.7842 1.74522 1.70894 1.67825 1.64901 1.6207 1.59395 1.56879 1.54554 1.52505 1.50491 1.48653 1.46946 1.45375 1.43799 1.42544 1.41164 1.39826 1.38656 1.37513 1.36322 1.35584 1.34742 1.33641 1.3227 1.31203 1.29936 1.28967 1.28299 1.27469 1.2583 1.27143 1.26034 1.29422 1.23926 1.21795 1.17647 1.33333 1.25 1 tree_stats_height=58 tree_stats_numInternals=9995639 tree_stats_numLeaves=9995640 tree_stats_numNodes=19991279 tree_stats_numKeys=9995639 tree_stats_avgDegreeInternal=2 tree_stats_avgDegreeLeaves=1 tree_stats_avgDegree=1.5 tree_stats_avgKeyDepth=30.1724 tree_stats_bytesAtDepth=40 80 160 320 640 1280 2560 5120 10240 20480 40960 81520 159920 309360 586000 1077040 1920720 3303680 5428800 8514640 12690560 17993600 24408480 31682560 39330560 46721120 53148880 57989280 60894080 61492480 59836480 56181360 50984080 44661040 38000800 31285360 24919440 19265920 14454640 10500320 7472960 5192480 3493600 2254800 1407120 842480 488080 276240 151760 78400 42560 22160 13040 6240 2720 960 640 320 tree_stats_sizeInBytes=799651160 6node_tIxPvE_object_size=40 6node_tIxPvE_allocated_count=0 6node_tIxPvE_allocated_size=0MB 6node_tIxPvE_get_from_pool=0 6node_tIxPvE_deallocated=0 6node_tIxPvE_limbo_count=0 6node_tIxPvE_limbo_details=0 0 0 global_epoch_counter=11205 log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:0 16:1 32:3 64:102 128:340094 256:24318 512:43 1024:94 (2^04, 2^05]: 1 (2^05, 2^06]: 3 (2^06, 2^07]: 102 (2^07, 2^08]: 340094 (2^08, 2^09]: 24318 (2^09, 2^10]: 43 (2^10, 2^11]: 94 sum_limbo_reclamation_event_size_total=82447792 count_limbo_reclamation_event_size_by_thread=7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7597 7596 7597 7597 7597 count_limbo_reclamation_event_size_total=364655 sum_limbo_reclamation_event_count_total=364752 first_thread_announced_epoch_by_thread=22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 22410 sum_num_inserts_by_thread=1712802 1719955 1731301 1732108 1724167 1712658 1715492 1718881 1735003 1736598 1719505 1710103 1717684 1726741 1733387 1736846 1724241 1714507 1708823 1718470 1724035 1730848 1711134 1703097 1714872 1720915 1730610 1731402 1724849 1709185 1711596 1723272 1736001 1736969 1718142 1709794 1720287 1726887 1737414 1736411 1723682 1713587 1705733 1716300 1721648 1728436 1715525 1705287 sum_num_inserts_total=82637190 sum_num_deletes_by_thread=1712774 1723339 1734566 1732476 1725920 1709049 1715156 1721882 1737141 1738220 1719700 1710455 1719814 1727720 1734985 1736955 1725019 1716064 1707919 1718153 1725541 1729812 1714439 1704010 1713555 1721749 1733556 1731790 1725879 1711027 1709610 1719960 1738303 1737514 1719566 1709603 1719869 1729530 1734646 1735875 1725534 1716128 1708424 1717717 1724901 1731784 1714183 1704503 sum_num_deletes_total=82676315 sum_num_searches_by_thread= sum_num_searches_total= sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=3425576 3443294 3465867 3464584 3450087 3421707 3430648 3440763 3472144 3474818 3439205 3420558 3437498 3454461 3468372 3473801 3449260 3430571 3416742 3436623 3449576 3460660 3425573 3407107 3428427 3442664 3464166 3463192 3450728 3420212 3421206 3443232 3474304 3474483 3437708 3419397 3440156 3456417 3472060 3472286 3449216 3429715 3414157 3434017 3446549 3460220 3429708 3409790 average_num_operations_total=3444031 stdev_num_operations_total=2869 sum_num_operations_total=165313505 min_num_operations_total=3407107 max_num_operations_total=3474818 first_time_thread_terminate_by_thread=10000130 10000132 10000131 10000131 10000132 10000131 10000132 10000130 10000130 10000130 10000131 10000131 10000132 10000131 10000131 10000132 10000131 10000130 10000132 10000131 10000132 10000130 10000130 10000131 10000131 10000132 10000131 10000130 10000130 10000131 10000130 10000130 10000131 10000130 10000130 10000130 10000130 10000131 10000130 10000130 10000130 10000132 10000130 10000130 10000131 10000130 10000131 10000130 min_time_thread_terminate_total=10000130 max_time_thread_terminate_total=10000132 first_time_thread_start_by_thread=3 3 3 3 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 3 3 3 3 3 3 3 3 3 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 3 3 3 3 3 min_time_thread_start_total=3 max_time_thread_start_total=4 sum_duration_all_ops_total= threads_final_keysum=99954469880083 threads_final_size=9995639 final_keysum=99954469880083 final_size=9995639 validate_result=success Validation OK. Structural validation OK. total_find=0 total_rq=0 total_inserts=82637190 total_deletes=82676315 total_updates=165313505 total_queries=0 total_ops=165313505 find_throughput=0 rq_throughput=0 update_throughput=16531350 query_throughput=0 total_throughput=16531350 total find : 0 total rq : 0 total inserts : 82637190 total deletes : 82676315 total updates : 165313505 total queries : 0 total ops : 165313505 find throughput : 0 rq throughput : 0 update throughput : 16531350 query throughput : 0 total throughput : 16531350 elapsed milliseconds : 10000 napping milliseconds overtime : 0 PAPI_L2_TCM=49.1482 PAPI_L3_TCM=18.2261 PAPI_TOT_CYC=8092.12 PAPI_TOT_INS=850.805 garbage=0 total_execution_walltime=14.932s REALTIME_MAIN_END_PERF_FORMAT=6987518.507449914 [time_cmd_output] time_elapsed_sec=15.14, faults_major=0, faults_minor=313263, mem_maxresident_kb=1255364, user_cputime=724.66, sys_cputime=4.56, percent_cpu=4815% timeout=false algorithm=guerraoui_ext_bst_ticket.debra thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=2