Boost logo

Boost-Build :

Subject: Re: [Boost-build] bjam 4.0.. in C++
From: Kevin Wheatley (hxpro_at_[hidden])
Date: 2010-05-25 06:47:46


Rene Rivera wrote:
> Everyone keeps saying that the header and dependency scanning is taking
> up a bulk, or a lot, of the time. But all the performance tests I've run
> never show that. Instead they show that the large memory use consequent
> of the way strings and variables are handled by bjam are the main
> performance problem. So.. Have I misdiagnosed this performance problem?
> Do people have measurements I should be looking at that show the
> dependency scanning problem?

The key here is how do you tell :-)

as an example, I've attached a does nothing invocation somewhere in
the middle of my tree.
bjam Version 3.1.17 + Milestone 12
Windows XP
MSVC 8.0
Boost 1.42.0

all source is local to the machine. This is the result of the 3rd run
one after the other to try cache everything in RAM.

I'd love to hear I'm doing something bad, generally I'm splitting up
the build into a number of libraries, with unit test executables per
library, with some dependencies between the libraries, but trying to
keep that down to a minimum.

Kevin

-- 
| Kevin Wheatley, Cinesite (Europe) Ltd | Nobody thinks this      |
| Senior Technology                     | My employer for certain |
| And Network Systems Architect         | Not even myself         |
Cinesite (Europe) Ltd. Registered Office: HemelOne, Boundary Way, Hemel Hempstead, Herts, HP2 7YU Registered in Cardiff No. 2820389 VAT No. 630 5446 60

bjam -d+10
warning: Building Boost.Regex with the optional Unicode/ICU support disabled.
note: Please refer to the Boost.Regex documentation for more information
note: this is a strictly optional feature.
 --count-- --gross-- --net-- --each-- --mem-- --each-- --name--
         1 17.480000 0.031000 0.03100000 332428 332428 MAIN
    416552 0.095000 0.032000 0.00000008 730164 1 BINDMODULE
    283345 0.411000 0.364000 0.00000128 2042847 7 MATCH
        41 0.515000 0.000000 0.00000000 504 12 GLOB
      5857 1.468000 1.373000 0.00023442 1896460 323 FILE_DIRSCAN
     61199 0.063000 0.063000 0.00000103 100432 1 BUILTIN_GLOB_BACK
         5 0.000000 0.000000 0.00000000 260 52 PWD
         1 0.000000 0.000000 0.00000000 268 268 find-to-root
     82898 1.064000 0.143000 0.00000173 11316784 136 timestamp
         1 7.904000 0.000000 0.00000000 109168 109168 boost-build
         1 0.000000 0.000000 0.00000000 108 108 _poke
       348 0.016000 0.000000 0.00000000 2340 6 RULENAMES
         1 0.000000 0.000000 0.00000000 24 24 HAS_NATIVE_RULE
       427 0.016000 0.000000 0.00000000 46136 108 IMPORT
     18381 0.048000 0.016000 0.00000087 3366500 183 NORMALIZE_PATH
       745 0.000000 0.000000 0.00000000 35388 47 modules.poke
      1764 7.904000 0.062000 0.00003515 2372764 1345 modules.import
     17767 0.016000 0.016000 0.00000090 68824 3 CALLER_MODULE
      2368 0.000000 0.000000 0.00000000 112516 47 modules.peek
       117 0.000000 0.000000 0.00000000 155216 1326 modules.normalize-raw-paths
       115 7.904000 0.063000 0.00054783 5481312 47663 modules.load
       115 0.000000 0.000000 0.00000000 35060 304 modules.record-binding
       115 0.000000 0.000000 0.00000000 18180 158 modules.run-module-test
      8732 0.000000 0.000000 0.00000000 47456 5 IMPORT_MODULE
       290 0.000000 0.000000 0.00000000 55612 191 modules.binding
         1 0.000000 0.000000 0.00000000 0 0 option.process
         5 0.000000 0.000000 0.00000000 0 0 NATIVE_RULE
         1 0.000000 0.000000 0.00000000 0 0 feature.setup
         1 0.000000 0.000000 0.00000000 0 0 string.whitespace
        84 0.000000 0.000000 0.00000000 2048 24 option.get
       118 0.000000 0.000000 0.00000000 0 0 EXPORT
         1 0.000000 0.000000 0.00000000 732 732 VARNAMES
        11 0.000000 0.000000 0.00000000 0 0 os.environ
         1 0.000000 0.000000 0.00000000 0 0 os.on-windows
       291 0.000000 0.000000 0.00000000 36 0 os.get-constant
       321 0.000000 0.000000 0.00000000 48 0 BACKTRACE
       301 0.000000 0.000000 0.00000000 1068 3 os.constant
        99 0.016000 0.000000 0.00000000 9576 96 feature.feature
       624 0.000000 0.000000 0.00000000 17384 27 feature.grist
       255 0.016000 0.000000 0.00000000 4356 17 feature.extend
       255 0.016000 0.016000 0.00006275 11764 46 feature.extend-feature
      2875 0.048000 0.032000 0.00001113 47436 16 feature.validate-feature
      2553 1.292000 0.015000 0.00000588 1372740 537 class.new
      2553 0.016000 0.016000 0.00000627 795880 311 class.xinit
      2553 0.000000 0.000000 0.00000000 0 0 INSTANCE
         2 0.000000 0.000000 0.00000000 0 0 class_at_property-map.__init__
      2745 0.000000 0.000000 0.00000000 252 0 numbers.increment
      6428 0.000000 0.000000 0.00000000 17396 2 CALC
       412 0.000000 0.000000 0.00000000 77696 188 path.parent
       412 0.000000 0.000000 0.00000000 19328 46 path.has-parent
     10190 0.201000 0.140000 0.00001374 1589220 155 regex.match
       254 0.231000 0.015000 0.00005906 261720 1030 path.glob
      3680 0.062000 0.016000 0.00000435 442624 120 path.root
     39661 0.171000 0.094000 0.00000237 2338308 58 path.is-rooted
      2901 0.078000 0.016000 0.00000552 473100 163 path.join
     11281 0.127000 0.111000 0.00000984 1444448 128 version.check-jam-version
         1 0.000000 0.000000 0.00000000 48 48 version.version-less
         5 0.000000 0.000000 0.00000000 96 19 numbers.check
       140 0.000000 0.000000 0.00000000 0 0 numbers.less
         1 0.000000 0.000000 0.00000000 0 0 version.is-all-zeroes
     32927 2.399000 0.016000 0.00000049 2113044 64 path.native
     32927 2.367000 0.313000 0.00000951 8428428 255 path.native-NT
     42301 1.868000 1.200000 0.00002837 11492644 271 regex.split
     33232 0.186000 0.138000 0.00000415 2275308 68 sequence.join
       508 0.048000 -0.015000 -0.00002953 21632 42 GLOB-RECURSIVELY
     14916 3.244000 0.248000 0.00001663 7335780 491 sequence.transform
     40750 2.997000 0.110000 0.00000270 67416 1 modules.call-in
      1115 0.016000 0.016000 0.00001435 189612 170 set.difference
      8494 0.374000 0.030000 0.00000353 1341372 157 path.make
      8496 0.344000 0.078000 0.00000918 5851036 688 path.make-NT
        10 0.000000 0.000000 0.00000000 1280 128 path.basename
        13 0.000000 0.000000 0.00000000 48 3 types/register.type
       109 0.000000 0.000000 0.00000000 4176 38 type.registered
        48 0.000000 0.000000 0.00000000 6824 142 type.register
        27 0.000000 0.000000 0.00000000 144 5 type.register-suffixes
        39 0.000000 0.000000 0.00000000 0 0 type.set-generated-target-suffix
        46 0.000000 0.000000 0.00000000 4160 90 type.set-generated-target-ps
        46 0.000000 0.000000 0.00000000 132 2 class_at_property-map.insert
       100 0.000000 0.000000 0.00000000 36428 364 feature.compose
     40966 0.263000 0.202000 0.00000493 699812 17 feature.attributes
        48 0.000000 0.000000 0.00000000 4100 85 type.type-to-rule-name
        48 0.000000 0.000000 0.00000000 0 0 generators.update-cached-information-with-a-new-type
      7688 0.000000 0.000000 0.00000000 492 0 type.base
         7 0.000000 0.000000 0.00000000 0 0 type.set-generated-target-prefix
         1 0.000000 0.000000 0.00000000 0 0 class_at_installed-exe-generator.__init__
        39 0.000000 0.000000 0.00000000 216 5 class_at_generator.__init__
        96 0.000000 0.000000 0.00000000 60 0 type.validate
        39 0.032000 0.000000 0.00000000 17668 453 generators.register
       561 0.000000 0.000000 0.00000000 0 0 class_at_generator.target-types
      2181 0.079000 0.047000 0.00002155 2367352 1085 sequence.unique
      2110 0.016000 0.016000 0.00000758 86752 41 SORT
      9818 0.015000 0.000000 0.00000000 0 0 class_at_[hidden]
        39 0.032000 0.032000 0.00082051 7488 192 generators.invalidate-extendable-viable-source-target-type-cache
         1 0.000000 0.000000 0.00000000 0 0 class_at_installed-shared-lib-generator.__init__
         2 0.000000 0.000000 0.00000000 0 0 builtin.default-host-os
         4 0.000000 0.000000 0.00000000 0 0 feature.set-default
         3 0.000000 0.000000 0.00000000 1056 352 numbers.range
         4 0.000000 0.000000 0.00000000 64 16 builtin.variant
       629 0.030000 0.000000 0.00000000 512 0 property.validate
       531 0.030000 -0.016000 -0.00003013 0 0 property.validate1
       531 0.000000 0.000000 0.00000000 4356 8 feature.valid
      1611 0.046000 0.031000 0.00001924 11804 7 feature.validate-value-string
       491 0.266000 0.109000 0.00022200 1430120 2912 property.refine
         2 0.000000 0.000000 0.00000000 0 0 feature.is-implicit-value
         4 0.000000 0.000000 0.00000000 256 64 scanner.register
         7 0.000000 0.000000 0.00000000 0 0 type.set-scanner
         1 0.000000 0.000000 0.00000000 0 0 class_at_lib-generator.__init__
         1 0.000000 0.000000 0.00000000 0 0 class_at_searched-lib-generator.__init__
         1 0.000000 0.000000 0.00000000 0 0 class_at_prebuilt-lib-generator.__init__
         6 0.000000 0.000000 0.00000000 0 0 generators.override
         1 1.109000 0.000000 0.00000000 2304 2304 build-system.load-configuration-files
         3 0.016000 0.000000 0.00000000 0 0 build-system.initialize-config-module
        61 2.548000 0.000000 0.00000000 182356 2989 project.initialize
        61 0.000000 0.000000 0.00000000 10112 165 class_at_project-attributes.__init__
      1107 0.016000 0.016000 0.00001445 0 0 property-set.empty
      3421 0.947000 0.030000 0.00000877 4462814 1304 property-set.create
      1100 0.854000 0.528000 0.00048000 7235744 6577 class_at_property-set.__init__
       701 0.077000 0.000000 0.00000000 36224 51 class_at_project-attributes.set
        61 0.031000 0.015000 0.00024590 61444 1007 modules.clone-rules
      3442 0.047000 0.016000 0.00000465 916548 266 project.attribute
      3761 0.031000 0.031000 0.00000824 87680 23 class_at_project-attributes.get
        59 0.031000 0.015000 0.00025424 18816 318 class_at_project-target.__init__
       777 0.000000 0.000000 0.00000000 8252 10 class_at_abstract-target.__init__
       778 0.000000 0.000000 0.00000000 44280 56 errors.nearest-user-location
       778 0.000000 0.000000 0.00000000 12 0 NEAREST_USER_LOCATION
       212 0.000000 0.000000 0.00000000 64256 303 project.target
        56 0.000000 0.000000 0.00000000 0 0 USER_MODULE
         1 0.000000 0.000000 0.00000000 0 0 os.home-directories
        57 0.032000 0.016000 0.00028070 84480 1482 project.inherit-attributes
       130 0.000000 0.000000 0.00000000 20992 161 project.attributes
      1280 0.000000 0.000000 0.00000000 564736 441 property.make
        55 0.016000 0.000000 0.00000000 22720 413 class_at_project-target.inherit
       908 0.000000 0.000000 0.00000000 87808 96 class_at_project-target.project-module
         2 1.093000 0.000000 0.00000000 3200 1600 build-system.load-config
        57 2.501000 0.000000 0.00000000 34944 613 project.load-used-projects
         1 0.000000 0.000000 0.00000000 0 0 utility.unquote
         2 0.297000 0.000000 0.00000000 2560 1280 project-rules.using
       357 0.094000 0.000000 0.00000000 30208 84 project-rules.import
         3 0.423000 0.000000 0.00000000 0 0 toolset.using
         6 0.016000 0.000000 0.00000000 0 0 builtin.register-c-compiler
         6 0.000000 0.000000 0.00000000 0 0 class_at_C-compiling-generator.__init__
       130 0.156000 0.000000 0.00000000 113348 871 toolset.flags
       102 0.077000 0.000000 0.00000000 39744 389 property.validate-property-sets
      4390 0.373000 0.063000 0.00001435 720896 164 feature.split
       102 0.079000 0.000000 0.00000000 141260 1384 toolset.normalize-condition
      1873 0.223000 0.016000 0.00000854 68632 36 feature.expand-subfeatures
      2621 0.191000 0.048000 0.00001831 144720 55 feature.expand-subfeatures-aux
       146 0.000000 0.000000 0.00000000 52704 360 toolset.add-flag
        16 0.000000 0.000000 0.00000000 0 0 generators.register-standard
         2 0.000000 0.000000 0.00000000 0 0 common.file-touch-command
         2 0.000000 0.000000 0.00000000 0 0 common.rm-command
         1 0.000000 0.000000 0.00000000 0 0 common.get-program-files-dir
         1 0.000000 0.000000 0.00000000 0 0 class_at_configurations.__init__
         1 0.000000 0.000000 0.00000000 8628 8628 msvc.auto-detect-toolset-versions
        79 0.000000 0.000000 0.00000000 12 0 W32_GETREG
         9 0.000000 0.000000 0.00000000 1752 194 msvc.register-configuration
         3 0.000000 0.000000 0.00000000 0 0 class_at_configurations.register
         3 0.000000 0.000000 0.00000000 584 194 class_at_configurations.set
         9 0.000000 0.000000 0.00000000 0 0 class_at_configurations.all
         8 0.000000 0.000000 0.00000000 768 96 msvc.default-path
         9 0.000000 0.000000 0.00000000 512 56 class_at_configurations.get
        49 0.000000 0.000000 0.00000000 83808 1710 feature.get-values
         1 0.016000 0.000000 0.00000000 0 0 msvc.register-toolset
         5 0.000000 0.000000 0.00000000 0 0 feature.values
         1 0.016000 0.000000 0.00000000 512 512 msvc.register-toolset-really
         3 0.000000 0.000000 0.00000000 0 0 feature.subfeature
         7 0.000000 0.000000 0.00000000 0 0 feature.get-subfeature-name
       223 0.000000 0.000000 0.00000000 52952 237 utility.ungrist
         4 0.000000 0.000000 0.00000000 0 0 feature.extend-subfeature
         4 0.000000 0.000000 0.00000000 0 0 feature.subvalue-var
         2 0.000000 0.000000 0.00000000 5184 2592 toolset.inherit-flags
         2 0.000000 0.000000 0.00000000 384 192 toolset.add-requirements
         2 0.000000 0.000000 0.00000000 0 0 class_at_linking-generator.__init__
         1 0.000000 0.000000 0.00000000 0 0 builtin.register-archiver
         1 0.000000 0.000000 0.00000000 0 0 class_at_archive-generator.__init__
         1 0.140000 0.000000 0.00000000 0 0 msvc.init
         1 0.140000 0.000000 0.00000000 0 0 msvc.configure
         1 0.140000 0.000000 0.00000000 24836 24836 msvc.configure-really
         1 0.000000 0.000000 0.00000000 0 0 class_at_configurations.used
         1 0.000000 0.000000 0.00000000 0 0 class_at_configurations.use
         1 0.000000 0.000000 0.00000000 448 448 common.check-init-parameters
         1 0.000000 0.000000 0.00000000 128 128 msvc.default-paths
         1 0.000000 0.000000 0.00000000 576 576 common.get-invocation-command
         1 0.000000 0.000000 0.00000000 512 512 common.get-invocation-command-nodefault
         1 0.000000 0.000000 0.00000000 256 256 common.check-tool
         1 0.000000 0.000000 0.00000000 384 384 common.check-tool-aux
         1 0.000000 0.000000 0.00000000 0 0 path.exists
         1 0.015000 0.000000 0.00000000 1408 1408 common.handle-options
       250 0.015000 0.000000 0.00000000 0 0 feature.implied-subfeature
       474 0.015000 0.015000 0.00003165 52544 110 feature.find-implied-subfeature
         1 0.000000 0.000000 0.00000000 256 256 common.get-absolute-tool-path
         1 0.000000 0.000000 0.00000000 512 512 msvc.locate-default-setup
         1 0.062000 0.000000 0.00000000 6724 6724 msvc.configure-version-specific
         1 0.000000 0.000000 0.00000000 0 0 toolset.push-checking-for-flags-module
         1 0.000000 0.000000 0.00000000 0 0 toolset.pop-checking-for-flags-module
        39 0.077000 0.000000 0.00000000 25920 664 project-rules.project
        39 0.015000 0.015000 0.00038462 4800 123 project.register-id
       602 0.000000 0.000000 0.00000000 12 0 project.current
         1 0.125000 0.000000 0.00000000 3456 3456 qt4.init
         2 0.000000 0.000000 0.00000000 0 0 project.push-current
        12 0.000000 0.000000 0.00000000 0 0 qt4.debug-message
         1 0.000000 0.000000 0.00000000 0 0 class_at_uic-generator.__init__
         1 0.000000 0.000000 0.00000000 0 0 class_at_moc-h-generator.__init__
         1 0.000000 0.000000 0.00000000 0 0 class_at_moc-inc-generator.__init__
         1 0.031000 0.000000 0.00000000 896 896 qt4.check-version
         4 0.125000 0.125000 0.03125000 1088 272 COMMAND
         1 0.000000 0.000000 0.00000000 1152 1152 qt4.read-output
       212 0.000000 0.000000 0.00000000 9412 44 feature.defaults
        39 0.078000 0.000000 0.00000000 128 3 project-rules.glob
        39 0.062000 0.000000 0.00000000 39744 1019 project.glob-internal
      2343 0.031000 0.000000 0.00000000 240256 102 class_at_project-target.get
       133 0.424000 0.016000 0.00012030 61376 461 builtin.lib
       430 0.094000 0.048000 0.00011163 4222028 9818 targets.main-target-sources
       430 0.690000 0.000000 0.00000000 203076 472 targets.main-target-requirements
       430 0.000000 0.000000 0.00000000 119296 277 toolset.requirements
       457 0.737000 0.047000 0.00010284 901760 1973 property-set.refine-from-user-input
       790 0.549000 0.016000 0.00002025 1402364 1775 property-set.create-from-user-input
       814 0.062000 0.015000 0.00001843 1030396 1265 property.translate-paths
      4208 0.063000 0.016000 0.00000380 766996 182 property.split-conditional
       790 0.031000 0.015000 0.00001899 685708 867 property.translate-indirect
      1486 0.032000 0.016000 0.00001077 380288 255 path.pwd
       790 0.062000 0.000000 0.00000000 1024004 1296 property.translate-dependencies
       790 0.267000 0.061000 0.00007722 1027840 1301 property.expand-subfeatures-in-conditions
       824 0.157000 0.000000 0.00000000 512580 622 class_at_property-set.refine
      3304 0.062000 0.047000 0.00001423 2991196 905 class_at_property-set.raw
       430 0.048000 0.016000 0.00003721 28288 65 targets.main-target-default-build
       430 0.016000 0.000000 0.00000000 512 1 property-set.create-with-validation
       334 0.031000 0.000000 0.00000000 53696 160 targets.main-target-usage-requirements
      1216 0.063000 0.000000 0.00000000 555404 456 class_at_property-set.add
       213 0.016000 0.000000 0.00000000 9920 46 class_at_typed-target.__init__
       435 0.032000 0.032000 0.00007356 938324 2157 class_at_basic-target.__init__
       435 0.000000 0.000000 0.00000000 103568 238 targets.main-target-alternative
       436 0.000000 0.000000 0.00000000 0 0 class_at_abstract-target.project
       435 0.000000 0.000000 0.00000000 13184 30 class_at_project-target.add-alternative
      8770 0.030000 0.030000 0.00000342 1433004 163 class.is-instance
        18 0.094000 0.000000 0.00000000 0 0 qt4.add-shared-library
        19 0.094000 0.000000 0.00000000 5376 282 qt4.add-library
        95 0.016000 0.000000 0.00000000 17796 187 project-rules.explicit
       208 0.000000 0.000000 0.00000000 256 1 class_at_project-target.mark-target-as-explicit
       116 0.109000 0.000000 0.00000000 24640 212 alias.alias
       119 0.000000 0.000000 0.00000000 1664 13 class_at_alias-target-class.__init__
         1 0.000000 0.000000 0.00000000 0 0 qt4.add-static-library
         2 0.000000 0.000000 0.00000000 0 0 project.pop-current
        54 0.154000 0.000000 0.00000000 11008 203 path.glob-in-parents
        54 0.016000 0.000000 0.00000000 26752 495 path.all-parents
         1 0.000000 0.000000 0.00000000 0 0 build-system.process-explicit-toolset-requests
         2 0.000000 0.000000 0.00000000 0 0 regex.split-list
        82 2.532000 0.000000 0.00000000 60288 735 project.find
       187 0.032000 0.016000 0.00008556 105092 561 project.module-name
       119 2.532000 0.000000 0.00000000 246080 2067 project.load
        55 2.532000 0.016000 0.00029091 144896 2634 project.load-jamfile
        55 0.032000 0.000000 0.00000000 44288 805 project.find-jamfile
        53 2.532000 0.000000 0.00000000 5504 103 project.load-parent
        53 0.000000 0.000000 0.00000000 6272 118 project.mark-as-user
         6 0.000000 0.000000 0.00000000 896 149 project-rules.path-constant
       148 0.000000 0.000000 0.00000000 21632 146 class_at_project-target.add-constant
        51 0.000000 0.000000 0.00000000 1280 25 project-rules.use-project
        24 0.000000 0.000000 0.00000000 704 29 property.remove
      2652 0.032000 0.016000 0.00000603 240 0 set.intersection
         1 0.000000 0.000000 0.00000000 0 0 common.file-creation-command
        34 0.031000 0.000000 0.00000000 6656 195 project-rules.build-project
        64 2.501000 0.000000 0.00000000 33536 524 project.use
         3 0.000000 0.000000 0.00000000 192 64 project-rules.constant
       197 0.000000 0.000000 0.00000000 7664 38 indirect.make
       126 0.032000 0.016000 0.00012698 32768 260 path.relative
        20 0.016000 0.000000 0.00000000 1408 70 Jamfile</D:/Boost/boost_1_42_0>.explicit-alias
         1 0.000000 0.000000 0.00000000 0 0 Jamfile</D:/Boost/boost_1_42_0>.libraries-to-install
         2 0.967000 0.000000 0.00000000 128 64 path.glob-tree
         9 0.546000 0.062000 0.00688889 4156520 461835 path..glob-tree
      7995 0.031000 0.015000 0.00000188 0 0 CHECK_IF_FILE
        16 0.344000 0.000000 0.00000000 711744 44484 package.install
       374 0.062000 0.062000 0.00016578 800688 2140 property.select
       198 0.000000 0.000000 0.00000000 395460 1997 property.change
        96 0.235000 0.000000 0.00000000 879956 9166 stage.install
        96 0.031000 0.015000 0.00015625 667684 6955 class_at_install-target-class.__init__
         2 0.000000 0.000000 0.00000000 640 320 generate.generate
         2 0.000000 0.000000 0.00000000 0 0 class_at_generated-target-class.__init__
      1392 0.063000 0.063000 0.00004526 943464 677 class_at_property-set.get
         3 0.016000 0.000000 0.00000000 576 192 targets.create-metatarget
         3 0.000000 0.000000 0.00000000 192 64 class_at_top-level-target.__init__
         5 0.000000 0.000000 0.00000000 0 0 message.message
         5 0.000000 0.000000 0.00000000 0 0 class_at_message-target-class.__init__
        15 0.219000 0.000000 0.00000000 7680 512 Jamfile</D:/Boost/boost_1_42_0>.boost-install
         1 0.000000 0.000000 0.00000000 0 0 class_at_mpi-test-generator.__init__
         2 0.000000 0.000000 0.00000000 0 0 mpi.configured
         2 0.000000 0.000000 0.00000000 0 0 Jamfile</D:/Boost/boost_1_42_0/libs/iostreams/build>.create-library
         1 0.000000 0.000000 0.00000000 0 0 Jamfile</D:/Boost/boost_1_42_0/libs/log/build>.default_logapi
      1189 0.269000 0.269000 0.00022624 31584 26 DEPENDS
        30 0.064000 0.000000 0.00000000 3904 130 type.main-target-rule
        80 0.000000 0.000000 0.00000000 0 0 type.type-from-rule-name
        79 0.142000 0.000000 0.00000000 37952 480 targets.create-typed-target
        25 0.000000 0.000000 0.00000000 1428 57 SUBST
         1 0.000000 0.000000 0.00000000 0 0 class_at_python-test-generator.__init__
         4 0.000000 0.000000 0.00000000 0 0 python.configured
         1 0.126000 0.000000 0.00000000 0 0 python.init
        14 0.000000 0.000000 0.00000000 0 0 python.debug-message
         1 0.126000 0.000000 0.00000000 576 576 python.configure
         1 0.000000 0.000000 0.00000000 0 0 python.candidate-interpreters
         1 0.000000 0.000000 0.00000000 1024 1024 python.windows-installed-pythons
        16 0.000000 0.000000 0.00000000 26312 1644 python.software-registry-value
         3 0.000000 0.000000 0.00000000 0 0 python.windows-path-to-native
         2 0.126000 0.000000 0.00000000 5248 2624 python.probe
         2 0.048000 0.000000 0.00000000 1920 960 python.invokes-cygwin-symlink
         1 0.000000 0.000000 0.00000000 0 0 os.executable-path
         1 0.000000 0.000000 0.00000000 326144 326144 string.words
         2 0.000000 0.000000 0.00000000 0 0 python.default-extension
         3 0.094000 0.000000 0.00000000 6660 2220 python.shell-cmd
         1 0.016000 0.000000 0.00000000 64 64 python.is-cygwin-symlink
         1 0.000000 0.000000 0.00000000 0 0 python.compute-default-paths
         2 0.000000 0.000000 0.00000000 0 0 python.path-to-native
         2 0.000000 0.000000 0.00000000 128 64 python.guess-windows-path
         1 0.000000 0.000000 0.00000000 0 0 python.system-library-dependencies
         1 0.000000 0.000000 0.00000000 64 64 python.register-extension-suffix
         1 0.000000 0.000000 0.00000000 0 0 python.declare-libpython-target
         1 0.000000 0.000000 0.00000000 0 0 python.split-version
         4 0.000000 0.000000 0.00000000 2880 720 common.prepend-path-variable-command
         4 0.000000 0.000000 0.00000000 0 0 os.expand-variable
         4 0.000000 0.000000 0.00000000 8640 2160 common.path-variable-setting-command
         4 0.000000 0.000000 0.00000000 9280 2320 common.variable-setting-command
         1 0.000000 0.000000 0.00000000 0 0 Jamfile</D:/Boost/boost_1_42_0/libs/python/build>.find-py3-version
         1 0.016000 0.000000 0.00000000 0 0 Jamfile</D:/Boost/boost_1_42_0/libs/python/build>.lib_boost_python
         3 0.000000 0.000000 0.00000000 128 42 Jamfile</D:/Boost/boost_1_42_0/libs/python/build>.cond
         1 0.000000 0.000000 0.00000000 0 0 Jamfile</D:/Boost/boost_1_42_0/libs/python/build>.unless
         1 0.000000 0.000000 0.00000000 0 0 Jamfile</D:/Boost/boost_1_42_0/libs/regex/build>.check-icu-config
         1 0.000000 0.000000 0.00000000 0 0 Jamfile</D:/Boost/boost_1_42_0/libs/thread/build>.default_threadapi
        25 0.094000 0.000000 0.00000000 7680 307 Jamfile</D:/cinesite>.run-tests
        49 0.094000 0.000000 0.00000000 18688 381 testing.run
        49 0.000000 0.000000 0.00000000 0 0 testing.handle-input-files
        49 0.094000 0.016000 0.00032653 19328 394 testing.make-test
        49 0.000000 0.000000 0.00000000 384 7 regex.replace
         1 0.000000 0.000000 0.00000000 192 192 cast.cast
         1 0.000000 0.000000 0.00000000 0 0 class_at_cast-target-class.__init__
       270 0.000000 0.000000 0.00000000 384 1 class_at_[hidden]
         1 0.000000 0.000000 0.00000000 0 0 class_at_abstract-target.rename
         1 0.000000 0.000000 0.00000000 0 0 build-request.from-command-line
         3 0.000000 0.000000 0.00000000 0 0 class_at_vector.__init__
         3 0.000000 0.000000 0.00000000 0 0 class_at_node.__init__
         2 0.000000 0.000000 0.00000000 0 0 class_at_vector.get-at
         2 0.000000 0.000000 0.00000000 0 0 class_at_node.get
        90 0.000000 0.000000 0.00000000 30464 338 class_at_property-set.free
        12 2.636000 0.000000 0.00000000 3328 277 class_at_project-target.generate
        12 0.000000 0.000000 0.00000000 0 0 class_at_project-target.targets-to-build
        27 0.000000 0.000000 0.00000000 90652 3357 class_at_project-target.build-main-targets
       195 0.000000 0.000000 0.00000000 384 1 class_at_main-target.__init__
       224 0.000000 0.000000 0.00000000 30408 135 class_at_main-target.add-alternative
       224 0.000000 0.000000 0.00000000 0 0 class_at_basic-target.default-build
       198 2.636000 0.000000 0.00000000 17152 86 class_at_main-target.generate
       198 0.000000 0.000000 0.00000000 0 0 targets.start-building
       246 0.016000 0.000000 0.00000000 384 1 class_at_property-set.expand
       198 0.174000 0.015000 0.00007576 49152 248 feature.expand
       200 0.080000 0.064000 0.00032000 251072 1255 feature.expand-composites
       198 0.048000 0.000000 0.00000000 0 0 class_at_main-target.apply-default-build
       198 0.048000 0.032000 0.00016162 0 0 targets.apply-default-build
       198 2.620000 0.016000 0.00008081 17540 88 class_at_main-target.generate-really
       198 0.031000 0.000000 0.00000000 13184 66 class_at_main-target.select-alternatives
       246 0.031000 0.000000 0.00000000 0 0 class_at_property-set.add-defaults
         3 0.031000 0.015000 0.00500000 4608 1536 feature.add-defaults
       111 0.016000 0.016000 0.00014414 384 3 feature.select-subfeatures
       211 0.030000 0.015000 0.00007109 204 0 sequence.filter
      1399 0.015000 0.000000 0.00000000 17400 12 feature.is-subfeature-of
        99 0.015000 0.000000 0.00000000 1068 10 feature.split-top-feature
       198 2.605000 0.000000 0.00000000 262928 1327 class_at_basic-target.generate
        75 0.000000 0.000000 0.00000000 0 0 build-system.command-line-free-features
        75 0.326000 0.000000 0.00000000 71180 949 targets.common-properties
        89 0.000000 0.000000 0.00000000 31104 349 class_at_property-set.incidental
        97 0.000000 0.000000 0.00000000 24704 254 class_at_property-set.base
        48 0.233000 0.000000 0.00000000 6144 128 targets.common-properties2
       685 0.016000 0.016000 0.00002336 111812 163 feature.expand-composite
       123 0.392000 0.000000 0.00000000 681979 5544 targets.evaluate-requirements
       123 0.000000 0.000000 0.00000000 14144 114 class_at_property-set.non-conditional
       131 0.000000 0.000000 0.00000000 22336 170 class_at_property-set.conditional
       192 0.016000 0.016000 0.00008333 381376 1986 property.evaluate-conditionals-in-context
       250 0.062000 0.000000 0.00000000 23360 93 class_at_property-set.add-raw
       150 0.000000 0.000000 0.00000000 32064 213 class_at_property-set.non-dependency
       164 0.000000 0.000000 0.00000000 26240 160 class_at_property-set.dependency
       225 2.137000 0.000000 0.00000000 42564 189 class_at_basic-target.generate-dependencies
       294 2.137000 0.000000 0.00000000 67716 230 targets.generate-from-reference
       294 0.048000 0.016000 0.00005442 24896 84 targets.resolve-reference
       369 0.032000 0.000000 0.00000000 32516 88 class_at_project-target.find
       209 0.032000 0.000000 0.00000000 33792 161 class_at_project-target.find-really
       134 0.000000 0.000000 0.00000000 15684 117 class_at_project-target.main-target
       294 0.000000 0.000000 0.00000000 0 0 class_at_property-set.propagated
       261 0.078000 0.000000 0.00000000 113152 433 indirect.call
       261 0.000000 0.000000 0.00000000 34560 132 indirect.get-module
       391 0.000000 0.000000 0.00000000 50816 129 indirect.get-rule
        24 0.000000 0.000000 0.00000000 10280 428 Jamfile</D:/Boost/boost_1_42_0>.handle-static-runtime
        88 0.000000 0.000000 0.00000000 1152 13 class_at_file-reference.__init__
        88 0.032000 0.000000 0.00000000 12672 144 class_at_file-reference.exists
       218 0.032000 0.016000 0.00007339 85760 393 class_at_file-reference.location
       130 0.078000 0.000000 0.00000000 1280 9 class_at_file-reference.generate
       130 0.078000 0.016000 0.00012308 90496 696 virtual-target.from-file
        82 0.000000 0.000000 0.00000000 5252 64 type.type
       284 0.655000 0.000000 0.00000000 9472 33 class_at_file-target.__init__
       290 0.655000 0.000000 0.00000000 15176 52 class_at_abstract-file-target.__init__
       290 0.000000 0.000000 0.00000000 1832 6 class_at_virtual-target.__init__
        46 1.774000 0.000000 0.00000000 1792 38 class_at_typed-target.construct
       732 1.665000 0.047000 0.00006421 9484 12 generators.construct
       732 0.032000 0.000000 0.00000000 7892 10 generators.ensure-type
     10785 0.015000 0.015000 0.00000139 0 0 class_at_abstract-file-target.type
       732 0.000000 0.000000 0.00000000 0 0 generators.increase-indent
       732 1.665000 0.016000 0.00002186 698972 954 generators.construct-really
       732 0.183000 0.061000 0.00008333 2239556 3059 generators.find-viable-generators
       173 0.107000 0.000000 0.00000000 299336 1730 generators.find-viable-generators-aux
      4277 0.062000 0.062000 0.00001450 744 0 type.all-bases
       218 0.045000 0.000000 0.00000000 34816 159 class_at_property-set.as-path
        54 0.045000 0.000000 0.00000000 510080 9445 property.as-path
         4 0.045000 0.015000 0.00375000 2304 576 feature.minimize
         4 0.000000 0.000000 0.00000000 1024 256 feature.move-subfeatures-to-the-end
       100 0.030000 0.000000 0.00000000 0 0 feature.select-subproperties
      1392 0.015000 0.000000 0.00000000 72 0 feature.is-subproperty-of
         8 0.000000 0.000000 0.00000000 0 0 sequence.insertion-sort
        17 0.000000 0.000000 0.00000000 0 0 property.path-order
         8 0.000000 0.000000 0.00000000 512 64 feature.implied-feature
        16 0.000000 0.000000 0.00000000 0 0 property.identity
      7212 0.015000 0.015000 0.00000208 0 0 generators.indent
      7394 0.030000 0.015000 0.00000203 12 0 generators.generators.dout
       669 0.000000 0.000000 0.00000000 0 0 class_at_generator.source-types
       522 0.047000 0.016000 0.00003065 634728 1215 class_at_generator.match-rank
       522 0.000000 0.000000 0.00000000 0 0 class_at_generator.requirements
      1122 0.016000 0.016000 0.00001426 106640 95 sequence.length
      2853 1.620000 0.094000 0.00003295 723772 253 generators.try-one-generator
      2853 0.016000 0.016000 0.00000561 1547136 542 generators.viable-source-types-for-generator
        17 0.000000 0.000000 0.00000000 1560 91 generators.viable-source-types-for-generator-real
       440 1.620000 0.000000 0.00000000 93772 213 generators.try-one-generator-really
        33 1.076000 0.000000 0.00000000 36484 1105 class_at_lib-generator.run
        16 0.000000 0.000000 0.00000000 48 3 generators.viable-source-types
        12 0.000000 0.000000 0.00000000 29152 2429 generators.viable-source-types-real
        75 0.000000 0.000000 0.00000000 144 1 type.all-derived
        73 0.733000 0.015000 0.00020548 245088 3357 class_at_linking-generator.run
      4092 0.062000 0.000000 0.00000000 672 0 type.is-derived
       294 1.496000 0.000000 0.00000000 4824 16 class_at_generator.run
       182 1.496000 0.000000 0.00000000 12464 68 class_at_generator.run-really
        37 1.094000 0.000000 0.00000000 27816 751 class_at_generator.convert-multiple-sources-to-consumable-types
       623 1.282000 0.015000 0.00002408 60856 97 class_at_generator.convert-to-consumable-types
       605 0.062000 0.047000 0.00007769 16764 27 class_at_generator.consume-directly
       341 1.236000 0.000000 0.00000000 6912 20 generators.construct-types
       182 0.826000 0.000000 0.00000000 4864 26 class_at_generator.construct-result
       182 0.795000 0.000000 0.00000000 0 0 class_at_generator.generated-targets
       127 0.000000 0.000000 0.00000000 4112 32 class_at_generator.determine-output-name
       508 0.000000 0.000000 0.00000000 5888 11 class_at_[hidden]
       127 0.000000 0.000000 0.00000000 24 0 utility.basename
       127 0.000000 0.000000 0.00000000 96 0 class_at_generator.determine-target-name
       127 0.000000 0.000000 0.00000000 0 0 class_at_C-compiling-generator.action-class
       127 0.000000 0.000000 0.00000000 84 0 class_at_compile-action.__init__
       188 0.000000 0.000000 0.00000000 180 0 class_at_action.__init__
       188 0.000000 0.000000 0.00000000 204 1 indirect.make-qualified
       208 0.000000 0.000000 0.00000000 780 3 class_at_action.add-targets
       193 0.655000 0.000000 0.00000000 203128 1052 class_at_abstract-file-target._adjust-name
       539 0.000000 0.000000 0.00000000 0 0 class_at_action.properties
       103 0.046000 0.000000 0.00000000 1772 17 Jamfile</D:/Boost/boost_1_42_0>.tag
       193 0.235000 0.000000 0.00000000 7748 40 virtual-target.add-prefix-and-suffix
       193 0.157000 0.000000 0.00000000 0 0 type.generated-target-suffix
       386 0.220000 0.016000 0.00004145 199424 516 type.generated-target-ps
       386 0.188000 0.015000 0.00003886 610224 1580 type.generated-target-ps-real
       409 0.173000 0.000000 0.00000000 610032 1491 class_at_property-map.find
       409 0.173000 0.142000 0.00034719 14065056 34388 class_at_property-map.find-replace
       409 0.000000 0.000000 0.00000000 0 0 sequence.select-highest-ranked
       193 0.078000 0.015000 0.00007772 0 0 type.generated-target-prefix
       208 0.125000 0.000000 0.00000000 224860 1081 virtual-target.register
       568 0.125000 0.000000 0.00000000 347328 611 class_at_file-target.path
       202 0.016000 0.000000 0.00000000 56832 281 class_at_property-set.target-path
        45 0.000000 0.000000 0.00000000 4096 91 property-set.hash-maybe
       202 0.000000 0.000000 0.00000000 44928 222 class_at_project-target.build-dir
       279 0.031000 0.000000 0.00000000 0 0 class.is-a
       279 0.031000 0.031000 0.00011111 0 0 class.is-derived
       575 0.000000 0.000000 0.00000000 0 0 class.bases
       732 0.000000 0.000000 0.00000000 0 0 generators.decrease-indent
       110 0.000000 0.000000 0.00000000 7168 65 class_at_searched-lib-generator.run
        76 0.671000 0.016000 0.00021053 37568 494 class_at_archive-generator.run
        15 0.140000 0.000000 0.00000000 3904 260 class_at_msvc-linking-generator.generated-targets
        15 0.140000 0.000000 0.00000000 53700 3580 class_at_linking-generator.generated-targets
        55 0.000000 0.000000 0.00000000 0 0 class_at_generator.action-class
        15 0.046000 0.000000 0.00000000 3392 226 common.format-name
        15 0.000000 0.000000 0.00000000 9856 657 common.toolset-tag
        45 0.000000 0.000000 0.00000000 0 0 common.join-tag
        15 0.000000 0.000000 0.00000000 9856 657 common.threading-tag
        15 0.000000 0.000000 0.00000000 68992 4599 common.runtime-tag
       228 0.000000 0.000000 0.00000000 0 0 class_at_abstract-file-target.action
        15 0.000000 0.000000 0.00000000 46784 3118 class_at_linking-generator.extra-usage-requirements
        75 0.000000 0.000000 0.00000000 768 10 virtual-target.recent-targets
        75 0.000000 0.000000 0.00000000 36036 480 class_at_basic-target.create-subvariant
        78 0.000000 0.000000 0.00000000 0 0 class_at_abstract-file-target.root
        75 0.000000 0.000000 0.00000000 1728 23 class_at_subvariant.__init__
       507 0.000000 0.000000 0.00000000 0 0 class_at_abstract-file-target.creating-subvariant
        75 0.000000 0.000000 0.00000000 0 0 virtual-target.clear-recent-targets
        75 0.487000 0.000000 0.00000000 189908 2532 class_at_basic-target.compute-usage-requirements
        75 0.000000 0.000000 0.00000000 0 0 class_at_subvariant.build-properties
       100 0.000000 0.000000 0.00000000 0 0 class_at_subvariant.sources-usage-requirements
        75 0.000000 0.000000 0.00000000 0 0 class_at_subvariant.set-usage-requirements
       210 0.000000 0.000000 0.00000000 0 0 targets.decrease-indent
       198 0.000000 0.000000 0.00000000 0 0 targets.end-building
        25 0.000000 0.000000 0.00000000 320 12 class_at_alias-target-class.construct
        25 0.204000 0.000000 0.00000000 7300 292 class_at_alias-target-class.compute-usage-requirements
         4 0.000000 0.000000 0.00000000 0 0 Jamfile</D:/Boost/boost_1_42_0/libs/regex/build>.force-shared-linking
         4 0.000000 0.000000 0.00000000 1280 320 class_at_message-target-class.construct
         3 0.000000 0.000000 0.00000000 0 0 ECHO
         3 0.000000 0.000000 0.00000000 0 0 class_at_prebuilt-lib-generator.run
         8 0.000000 0.000000 0.00000000 3328 416 class_at_basic-target.match
         6 0.000000 0.000000 0.00000000 0 0 class_at_null-action.__init__
         6 0.000000 0.000000 0.00000000 128 21 class_at_searched-lib-target.__init__
         6 0.000000 0.000000 0.00000000 0 0 class_at_searched-lib-target.path
       332 0.000000 0.000000 0.00000000 5760 17 class_at_searched-lib-target.search
        31 0.000000 0.000000 0.00000000 0 0 class_at_searched-lib-target.shared
        14 0.000000 0.000000 0.00000000 0 0 class_at_action.action-name
       144 0.000000 0.000000 0.00000000 0 0 class_at_action.sources
       429 1.517000 0.000000 0.00000000 327640 763 class_at_virtual-target.actualize
       429 1.517000 0.000000 0.00000000 528980 1233 class_at_abstract-file-target.actualize-no-scanner
       429 0.016000 0.016000 0.00003730 248540 579 class_at_abstract-file-target.actual-name
       218 0.000000 0.000000 0.00000000 103440 474 class_at_abstract-file-target.grist
       143 0.000000 0.000000 0.00000000 118536 828 virtual-target.register-actual-name
       290 0.441000 0.000000 0.00000000 473676 1633 class_at_file-target.actualize-location
       336 0.299000 0.032000 0.00009524 266160 792 common.MkDir
       336 0.141000 0.141000 0.00041964 0 0 NOUPDATE
       218 1.517000 0.000000 0.00000000 14408 66 class_at_abstract-file-target.actualize-action
       143 1.517000 0.016000 0.00011189 311364 2177 class_at_action.actualize
        33 0.000000 0.000000 0.00000000 0 0 class_at_action.adjust-properties
       130 0.000000 0.000000 0.00000000 0 0 class_at_action.targets
       130 1.486000 0.000000 0.00000000 0 0 class_at_action.actualize-sources
       260 1.486000 0.000000 0.00000000 83916 322 class_at_action.actualize-source-type
       282 0.016000 0.000000 0.00000000 540 1 type.get-scanner
        97 0.000000 0.000000 0.00000000 18624 192 class_at_compile-action.adjust-properties
        97 0.000000 0.000000 0.00000000 0 0 class_at_subvariant.implicit-includes
        27 0.000000 0.000000 0.00000000 48 1 class_at_subvariant.all-target-directories
        27 0.000000 0.000000 0.00000000 156 5 class_at_subvariant.compute-target-directories
        97 0.016000 0.000000 0.00000000 57688 594 scanner.get
        17 0.000000 0.000000 0.00000000 20800 1223 class_at_c-scanner.__init__
        17 0.000000 0.000000 0.00000000 0 0 class_at_scanner.__init__
        72 0.000000 0.000000 0.00000000 69632 967 scanner.install
        72 0.000000 0.000000 0.00000000 0 0 class_at_c-scanner.pattern
       130 0.949000 0.095000 0.00073077 1478848 11375 toolset.set-target-variables
       155 0.823000 0.110000 0.00070968 8060768 52004 toolset.set-target-variables-aux
      1089 0.109000 0.015000 0.00001377 771980 708 toolset.handle-flag-value
      2233 0.573000 0.247000 0.00011061 7902328 3538 toolset.find-property-subset
        96 0.016000 0.000000 0.00000000 37940 395 msvc.compile.c++
        97 0.000000 0.000000 0.00000000 95340 982 msvc.get-rspline
        97 0.016000 0.000000 0.00000000 153144 1578 msvc.compile-c-c++
        19 0.000000 0.000000 0.00000000 6992 368 msvc.archive
         2 0.000000 0.000000 0.00000000 0 0 NOTFILE
         5 0.000000 0.000000 0.00000000 22264 4452 msvc.link.dll
         1 0.000000 0.000000 0.00000000 512 512 msvc.compile.c
         3 0.000000 0.000000 0.00000000 65496 21832 msvc.link
         3 0.000000 0.000000 0.00000000 51356 17118 testing.capture-output
      6350 0.936000 0.920000 0.00014488 1344864 211 INCLUDES
      6350 0.296000 0.281000 0.00004425 0 0 NOCARE
         3 0.000000 0.000000 0.00000000 52304 17434 testing.run-path-setup
         3 0.000000 0.000000 0.00000000 1280 426 testing.expect-success
         3 0.000000 0.000000 0.00000000 120 40 testing.**passed**
         3 0.000000 0.000000 0.00000000 0 0 RMOLD
         1 0.000000 0.000000 0.00000000 0 0 UPDATE
         1 9.545000 0.000000 0.00000000 25848 25848 MAIN_MAKE
         1 9.515000 2.190000 2.19000000 3342640 3342640 MAKE_MAKE0
      6347 6.978000 0.123000 0.00001938 4538292 715 scanner.hdrrule
      6347 6.840000 1.017000 0.00016023 39267324 6186 class_at_c-scanner.process
     12694 0.064000 0.032000 0.00000252 489004 38 regex.transform
      6347 1.343000 1.220000 0.00019222 14815580 2334 scanner.propagate
      6347 0.472000 0.472000 0.00007437 0 0 ISFILE
         1 0.015000 0.015000 0.01500000 179288 179288 MAKE_MAKE1
  27923922 2.506000 2.506000 0.00000009 23174360 0 [OTHER]
         0 17.480000 0.000000 0.00000000 260859692 260859692 [TOTAL]
variables table: 151+297+891 (6K+3K) items+table+hash, 1.227642 density
file_info table: 45107+72171+216513 (2819K+845K) items+table+hash, 1.261875 density
targets table: 8105+24057+72171 (2443K+281K) items+table+hash, 1.122732 density
bindings table: 32416+72171+216513 (1691K+845K) items+table+hash, 1.175089 density
strings table: 140905+216513+649539 (2537K+2537K) items+table+hash, 1.275689 density


Boost-Build list run by bdawes at acm.org, david.abrahams at rcn.com, gregod at cs.rpi.edu, cpdaniel at pacbell.net, john at johnmaddock.co.uk