__step=000229
__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" ./brown_int_bst_tm_auto.debra.hybridnorec -nwork 144 -nprefill 144 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000229.txt
__path_data=data/data000229.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997251.560136291
binary=./brown_int_bst_tm_auto.debra.hybridnorec
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_int_bst_tm_auto
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=2000000
PREFILL_THREADS=144
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=144
WORK_THREADS=144
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=32
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63,64,65,66,67,68,69,70,71,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167
Hybrid noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.08s
prefilling_size=210607 prefilling_elapsed_millis=1s
prefilling_size=229174 prefilling_elapsed_millis=1.101s
prefilling_size=247361 prefilling_elapsed_millis=1.201s
prefilling_size=265144 prefilling_elapsed_millis=1.301s
prefilling_size=282322 prefilling_elapsed_millis=1.401s
prefilling_size=299113 prefilling_elapsed_millis=1.501s
prefilling_size=315485 prefilling_elapsed_millis=1.602s
prefilling_size=331635 prefilling_elapsed_millis=1.702s
prefilling_size=347292 prefilling_elapsed_millis=1.802s
prefilling_size=362607 prefilling_elapsed_millis=1.902s
prefilling_size=377720 prefilling_elapsed_millis=2.002s
prefilling_size=392704 prefilling_elapsed_millis=2.103s
prefilling_size=406897 prefilling_elapsed_millis=2.203s
prefilling_size=421003 prefilling_elapsed_millis=2.303s
prefilling_size=434690 prefilling_elapsed_millis=2.403s
prefilling_size=447911 prefilling_elapsed_millis=2.503s
prefilling_size=460854 prefilling_elapsed_millis=2.604s
prefilling_size=473357 prefilling_elapsed_millis=2.704s
prefilling_size=485415 prefilling_elapsed_millis=2.804s
prefilling_size=497559 prefilling_elapsed_millis=2.904s
prefilling_size=509681 prefilling_elapsed_millis=3.004s
prefilling_size=520909 prefilling_elapsed_millis=3.105s
prefilling_size=532065 prefilling_elapsed_millis=3.205s
prefilling_size=543202 prefilling_elapsed_millis=3.305s
prefilling_size=553992 prefilling_elapsed_millis=3.405s
prefilling_size=564668 prefilling_elapsed_millis=3.506s
prefilling_size=575077 prefilling_elapsed_millis=3.606s
prefilling_size=585135 prefilling_elapsed_millis=3.706s
prefilling_size=594959 prefilling_elapsed_millis=3.806s
prefilling_size=604431 prefilling_elapsed_millis=3.906s
prefilling_size=613771 prefilling_elapsed_millis=4.007s
prefilling_size=622832 prefilling_elapsed_millis=4.107s
prefilling_size=631536 prefilling_elapsed_millis=4.207s
prefilling_size=640203 prefilling_elapsed_millis=4.307s
prefilling_size=648453 prefilling_elapsed_millis=4.408s
prefilling_size=656735 prefilling_elapsed_millis=4.508s
prefilling_size=664678 prefilling_elapsed_millis=4.608s
prefilling_size=672521 prefilling_elapsed_millis=4.708s
prefilling_size=680307 prefilling_elapsed_millis=4.808s
prefilling_size=687709 prefilling_elapsed_millis=4.909s
prefilling_size=695216 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 304721 keys with 144 threads...
tid=0 thread_prefill_amount=6940 percent_done_estimate=328.0 elapsed_s=5 est_remaining_s=-8 / -3
tid=0 thread_prefill_amount=8328 percent_done_estimate=393.6 elapsed_s=6 est_remaining_s=-10 / -4
timing_elapsed 1.08087s
prefilling_size=1000000 prefilling_elapsed_millis=1.083s
finished prefilling to size 1000000 for expected size 1000000 keysum=999594792136, performing 2916669 updates; total_prefilling_elapsed_ms=1083 ms)
pref_size=1000000
pref_millis=1083
prefill_elapsed_ms=1083
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0
global_epoch_counter=21
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6997258.60159573
REALTIME_END_PERF_FORMAT=6997268.60251446
###############################################################################
################################## 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 (144 threads)...
partitioned into 951 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.015s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 251 488 915 1699 2998 5055 8122 12185 17402 23463 30359 37524 44470 50379 54156 55480 54414 50961 45866 39392 32493 25622 19518 14326 10043 6669 4328 2629 1486 803 459 262 113 55 17 7 2 2 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 3 9 36 65 225 537 1085 2181 3817 6109 8912 12385 16260 20526 24618 27967 29654 30396 29222 27416 24242 20552 16686 12764 9709 7021 4623 3100 1958 1116 581 329 211 87 47 13 7 1 2 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 254 497 951 1764 3223 5592 9207 14366 21219 29572 39271 49909 60730 70905 78774 83447 84068 81357 75088 66808 56735 46174 36204 27090 19752 13690 8951 5729 3444 1919 1040 591 324 142 64 20 9 3 3 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 254 497 951 1764 3223 5592 9207 14366 21219 29572 39271 49909 60730 70905 78774 83447 84068 81357 75088 66808 56735 46174 36204 27090 19752 13690 8951 5729 3444 1919 1040 591 324 142 64 20 9 3 3 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.98438 1.9685 1.93159 1.89274 1.86395 1.80484 1.74249 1.67818 1.62885 1.57354 1.53456 1.49782 1.46497 1.43529 1.40047 1.37184 1.34259 1.32049 1.29656 1.2789 1.25959 1.24114 1.22918 1.20915 1.2003 1.18464 1.16669 1.15652 1.14226 1.12573 1.1235 1.12692 1.10491 1.08951 1.06338 1.04688 1.1 1.11111 1.33333 1.33333 1
tree_stats_height=48
tree_stats_numInternals=654669
tree_stats_numLeaves=344474
tree_stats_numNodes=999143
tree_stats_numKeys=999143
tree_stats_avgDegreeInternal=1.52618
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34477
tree_stats_avgKeyDepth=23.897
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0
global_epoch_counter=463
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:13 4:1001 8:21290 16:37862 32:3194
(2^01, 2^02]: 13
(2^02, 2^03]: 1001
(2^03, 2^04]: 21290
(2^04, 2^05]: 37862
(2^05, 2^06]: 3194
sum_limbo_reclamation_event_size_total=1223510
count_limbo_reclamation_event_size_by_thread=440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440 440
count_limbo_reclamation_event_size_total=63360
sum_limbo_reclamation_event_count_total=63744
first_thread_announced_epoch_by_thread=926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926 926
sum_num_inserts_by_thread=25286 23506 22585 22714 22289 22651 25668 23778 23708 23752 23936 22894 24906 24050 23281 23568 23932 23389 25625 23172 23810 23743 22852 22184 25825 21320 22875 22751 22816 21981 25977 22639 23185 22789 23326 23237 26268 23646 23429 23158 23648 22686 26019 24143 24080 23578 23248 23440 18233 17129 16634 16403 16459 16746 18695 17407 16732 16640 16576 16506 18615 17174 16206 16863 16532 16465 18340 16689 17209 17038 16852 16261 17796 17205 16624 16595 15992 17289 17858 17565 16759 16678 16738 15567 18637 16911 16524 16731 16609 16621 17823 17731 16695 17018 16814 16547 10882 10520 10227 10300 10528 10783 11662 10817 10513 10468 10436 10558 11229 10622 10579 10740 10341 10282 11415 10784 10953 10591 10437 10756 11229 10480 10291 10249 10838 10526 11344 10888 10463 10905 10493 9980 11208 10724 10388 10820 10148 10185 10954 10784 10694 10579 10478 10650
sum_num_inserts_total=2460795
sum_num_deletes_by_thread=25425 23492 22683 22569 22677 22696 25446 24069 23728 23590 23943 22905 25099 24128 23005 23600 23479 23500 25559 22906 23981 23705 23118 21930 25753 21288 22605 22669 22928 22348 25504 22281 22820 22824 23487 23309 25912 23625 23384 23004 23647 22952 25889 24338 24218 23544 23230 23051 18168 17245 16733 16478 16395 16815 18803 17375 16947 16768 16772 16442 18408 17295 16551 16601 16774 16525 18355 16711 17201 16871 16926 16158 17917 16771 16653 16746 16222 17239 17946 17552 16908 16595 16859 15764 18399 17163 16752 16878 16459 16700 17735 17412 16951 16899 16902 16587 10908 10676 10109 10343 10557 10645 11570 10840 10596 10395 10480 10277 11116 10512 10664 10826 10314 10154 11416 10806 10750 10393 10538 10427 11056 10814 10367 10460 10934 10614 11462 10884 10495 10884 10552 10104 10935 10654 10278 10901 10200 10198 10865 10736 10661 10404 10737 10631
sum_num_deletes_total=2460307
sum_num_searches_by_thread=457315 419082 409956 406361 404458 406255 459417 427121 426379 424974 430342 412258 447963 430032 414757 424654 427857 420118 461457 412874 430090 424949 412019 396984 462060 386432 411521 408887 414098 401554 463416 407985 414290 413793 420379 419605 471234 426200 419744 412559 424935 410409 464659 436772 434179 423978 418000 423160 329602 308434 300114 296003 292231 307066 333926 310571 300954 301663 299208 293505 331979 308450 294698 300746 298185 298648 329286 301130 308774 306560 304710 290768 320121 303259 300510 301673 290846 309777 324447 316910 303722 299108 302609 279606 333840 304075 299434 302340 296957 298509 317938 314838 303635 307519 302754 296709 196956 187510 182793 187722 190490 192592 208160 195399 190754 187775 187723 189617 199569 188073 191741 193421 184700 184121 205218 192441 194200 187231 190806 190810 199684 190950 185317 186318 193299 188879 206369 194927 186509 197616 188885 180713 197813 193818 184508 194948 184409 179971 194490 196065 195562 187121 190650 193836
sum_num_searches_total=44248347
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=508026 466080 455224 451644 449424 451602 510531 474968 473815 472316 478221 458057 497968 478210 461043 471822 475268 467007 512641 458952 477881 472397 457989 441098 513638 429040 457001 454307 459842 445883 514897 452905 460295 459406 467192 466151 523414 473471 466557 458721 472230 456047 516567 485253 482477 471100 464478 469651 366003 342808 333481 328884 325085 340627 371424 345353 334633 335071 332556 326453 369002 342919 327455 334210 331491 331638 365981 334530 343184 340469 338488 323187 355834 337235 333787 335014 323060 344305 360251 352027 337389 332381 336206 310937 370876 338149 332710 335949 330025 331830 353496 349981 337281 341436 336470 329843 218746 208706 203129 208365 211575 214020 231392 217056 211863 208638 208639 210452 221914 209207 212984 214987 205355 204557 228049 214031 215903 208215 211781 211993 221969 212244 205975 207027 215071 210019 229175 216699 207467 219405 209930 200797 219956 215196 205174 216669 204757 200354 216309 217585 216917 208104 211865 215117
average_num_operations_total=341454
stdev_num_operations_total=8957
sum_num_operations_total=49169449
min_num_operations_total=200354
max_num_operations_total=523414
first_time_thread_terminate_by_thread=10000349 10000113 10000130 10000112 10000123 10000232 10000324 10000228 10000341 10000343 10000285 10000093 10000091 10000326 10000352 10000347 10000116 10000248 10000230 10000162 10000320 10000223 10000265 10000226 10000339 10000337 10000094 10000095 10000123 10000336 10000353 10000106 10000340 10000359 10000136 10000299 10000298 10000094 10000096 10000347 10000101 10000129 10000128 10000250 10000091 10000119 10000327 10000093 10000149 10000207 10000093 10000099 10000144 10000301 10000253 10000331 10000215 10000246 10000345 10000333 10000292 10000202 10000247 10000238 10000218 10000296 10000289 10000170 10000259 10000260 10000321 10000200 10000147 10000287 10000093 10000221 10000142 10000243 10000194 10000330 10000212 10000304 10000152 10000305 10000292 10000234 10000216 10000255 10000155 10000329 10000192 10000168 10000293 10000295 10000302 10000175 10000375 10000366 10000275 10000378 10000374 10000358 10000355 10000357 10000363 10000370 10000376 10000351 10000370 10000180 10000310 10000373 10000095 10000361 10000185 10000315 10000283 10000372 10000368 10000362 10000377 10000377 10000159 10000365 10000373 10000280 10000198 10000368 10000371 10000371 10000376 10000308 10000375 10000313 10000095 10000374 10000272 10000365 10000269 10000369 10000363 10000372 10000367 10000191
min_time_thread_terminate_total=10000091
max_time_thread_terminate_total=10000378
first_time_thread_start_by_thread=8 4 3 4 4 3 4 3 4 3 3 4 4 4 3 3 3 4 3 4 3 4 3 4 3 4 3 4 4 3 4 3 4 3 3 4 4 4 3 3 3 4 3 4 3 4 3 4 4 4 4 3 4 3 4 4 3 4 4 3 4 4 3 4 4 4 3 3 3 3 4 4 4 4 4 3 4 3 4 4 3 4 4 3 4 4 3 4 4 4 3 3 3 3 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=
threads_final_keysum=998889959856
threads_final_size=999143
final_keysum=998889959856
final_size=999143
validate_result=success
Validation OK.
Structural validation OK.
total_find=44248347
total_rq=0
total_inserts=2460795
total_deletes=2460307
total_updates=4921102
total_queries=44248347
total_ops=49169449
find_throughput=4424834
rq_throughput=0
update_throughput=492110
query_throughput=4424834
total_throughput=4916944
total find : 44248347
total rq : 0
total inserts : 2460795
total deletes : 2460307
total updates : 4921102
total queries : 44248347
total ops : 49169449
find throughput : 4424834
rq throughput : 0
update throughput : 492110
query throughput : 4424834
total throughput : 4916944
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=23.9736
PAPI_L3_TCM=3.82712
PAPI_TOT_CYC=81354.2
PAPI_TOT_INS=7314.57
garbage=21970722197032
total_execution_walltime=16.616s
REALTIME_MAIN_END_PERF_FORMAT=6997268.189255799
Hybrid noREC system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 48662119
[slow htm]
[fallback]
total fallback commit : 3424000
seconds global lock is held : 0.302939
[time_cmd_output] time_elapsed_sec=16.77, faults_major=0, faults_minor=98545, mem_maxresident_kb=389704, user_cputime=2332.06, sys_cputime=6.35, percent_cpu=13941%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.hybridnorec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1