********** initial-run-of-task-import-000 FINISHED **********

Iterative task information:
  Successfully processed:    500 in     713074 ms =   1426.1 ms per object, last: ShadowType:u-00000498 (u-00000498 (ACCOUNT - default - AccountObjectClass), 12e474e3-3976-4aa7-be26-9255e9ef6bd2) on Tue May 04 04:57:04 CEST 2021 in 1202 ms
  Failed:                      0 in          0 ms =      0.0 ms per object

Actions executed:
  All object actions:
    ADD        UserType                       http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:    500 time(s), last: u-00000499 (User null (u-00000499), 65ca9224-98a5-4c93-af3a-788cb62729c4) on Tue May 04 04:57:04 CEST 2021
    MODIFY     UserType                       http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:   3000 time(s), last: u-00000498 (User null (u-00000498), 42742585-dc2b-4841-86f8-b9003fc960c2) on Tue May 04 04:57:04 CEST 2021
    ADD        ShadowType                     http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:   2500 time(s), last: null (Shadow null (ACCOUNT - default - AccountObjectClass), 9c67cc59-682e-478f-af0d-9675c505b438) on Tue May 04 04:57:04 CEST 2021
    MODIFY     ShadowType                     http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:   1000 time(s), last: u-00000499 (Shadow u-00000499 (ACCOUNT - default - AccountObjectClass), d3c82763-1bee-47e0-a791-e43a95c733c2) on Tue May 04 04:57:04 CEST 2021
  Resulting object actions:
    ADD        UserType                       http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:    500 time(s), last: u-00000499 (User null (u-00000499), 65ca9224-98a5-4c93-af3a-788cb62729c4) on Tue May 04 04:57:04 CEST 2021
    ADD        ShadowType                     http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:   2500 time(s), last: null (Shadow null (ACCOUNT - default - AccountObjectClass), 934985ae-16f6-41c6-a239-b52e3224dd71) on Tue May 04 04:57:04 CEST 2021
    MODIFY     ShadowType                     http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:    500 time(s), last: u-00000499 (Shadow u-00000499 (ACCOUNT - default - AccountObjectClass), d3c82763-1bee-47e0-a791-e43a95c733c2) on Tue May 04 04:57:04 CEST 2021

Environmental performance information:
  Provisioning:
    source-000 (AccountObjectClass)                              count:        1, total time:      144 ms [min:      0, max:     23, avg:   144.0] GET:     0:  0 SEARCH:     1:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-001 (GroupObjectClass)                                count:     2871, total time:      206 ms [min:      0, max:     15, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:  2871:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-001 (AccountObjectClass)                              count:      500, total time:       89 ms [min:      0, max:     12, avg:     0.2] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-004 (AccountObjectClass)                              count:      500, total time:      122 ms [min:      0, max:      1, avg:     0.2] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-003 (AccountObjectClass)                              count:      500, total time:      147 ms [min:      0, max:     17, avg:     0.3] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-003 (GroupObjectClass)                                count:     4120, total time:      368 ms [min:      0, max:     17, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:  4120:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-002 (AccountObjectClass)                              count:      500, total time:      140 ms [min:      0, max:     22, avg:     0.3] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-004 (GroupObjectClass)                                count:     4367, total time:      519 ms [min:      0, max:     32, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:  4367:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-000 (GroupObjectClass)                                count:     4289, total time:      265 ms [min:      0, max:     10, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:  4289:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-002 (GroupObjectClass)                                count:     3687, total time:      268 ms [min:      0, max:      1, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:  3687:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-000 (AccountObjectClass)                              count:      500, total time:       74 ms [min:      0, max:      1, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
  Mappings:
    RoleType (aggregated)                    count:  40000, total time:  17758 ms [min:     0, max:    67, avg:     0.4]
    target-002                               count:    500, total time:     43 ms [min:     0, max:     1, avg:     0.1]
    target-000                               count:    500, total time:     42 ms [min:     0, max:     2, avg:     0.1]
    target-001                               count:    500, total time:     48 ms [min:     0, max:     1, avg:     0.1]
    source-000                               count: 103000, total time:  98170 ms [min:     0, max:   280, avg:     1.0]
    target-004                               count:    500, total time:     43 ms [min:     0, max:    10, avg:     0.1]
    target-003                               count:    500, total time:     49 ms [min:     0, max:     5, avg:     0.1]
  Last message: Successfully finished evaluation of mapping mapping in inbound expression for a-multi-0049 in resource:3d183de1-9a29-4b48-9302-1917c8c0d7f3(source-000) in 0 ms.
  On:           2021-05-04T04:57:04.937+02:00

Repository performance information:
  addObject.ShadowType:                count:   3000, total time:    20759 ms [min:     2, max:    63, avg:     6.9], wasted time for    1 retry/retries:       38 ms [min:     0, max:    38, avg:     0.0] (  0.18%)
  addObject.UserType:                  count:    500, total time:    16004 ms [min:    21, max:   123, avg:    32.0], wasted time for    1 retry/retries:       48 ms [min:     0, max:    48, avg:     0.1] (  0.30%)
  getObject.ArchetypeType:             count:      2, total time:        2 ms [min:     1, max:     1, avg:     1.0]
  getObject.ObjectTemplateType:        count:      2, total time:        0 ms [min:     0, max:     0, avg:     0.0]
  getObject.RoleType:                  count:    327, total time:      304 ms [min:     0, max:    19, avg:     0.9]
  getObject.ShadowType:                count:   6500, total time:     6960 ms [min:     0, max:    41, avg:     1.1]
  getObject.SystemConfigurationType:   count:      1, total time:        2 ms [min:     2, max:     2, avg:     2.0]
  getObject.TaskType:                  count:      4, total time:       12 ms [min:     1, max:     6, avg:     3.0]
  getObject.UserType:                  count:   2504, total time:    33208 ms [min:     0, max:   101, avg:    13.3]
  getVersion.ResourceType:             count:     12, total time:        6 ms [min:     0, max:     1, avg:     0.5]
  getVersion.SystemConfigurationType:  count:      2, total time:        1 ms [min:     0, max:     1, avg:     0.5]
  modifyObject.ShadowType:             count:   4500, total time:    25487 ms [min:     2, max:    85, avg:     5.7], wasted time for   47 retry/retries:     1584 ms [min:     0, max:    79, avg:     0.4] (  6.21%)
  modifyObject.TaskType:               count:     44, total time:     1148 ms [min:     7, max:    62, avg:    26.1]
  modifyObject.UserType:               count:   3000, total time:   193024 ms [min:    41, max:   794, avg:    64.3], wasted time for   71 retry/retries:     6723 ms [min:     0, max:   721, avg:     2.2] (  3.48%)
  searchObjects.FunctionLibraryType:   count:      1, total time:       12 ms [min:    12, max:    12, avg:    12.0]
  searchObjects.RoleType:              count:    206, total time:      747 ms [min:     0, max:    40, avg:     3.6]
  searchObjects.ShadowType:            count:    849, total time:     1621 ms [min:     0, max:    43, avg:     1.9]
  searchObjects.TaskType:              count:   1500, total time:     6631 ms [min:     1, max:    42, avg:     4.4]
  searchObjects.UserType:              count:    500, total time:      414 ms [min:     0, max:    35, avg:     0.8]
  searchShadowOwner.FocusType:         count:    500, total time:      474 ms [min:     0, max:    46, avg:     0.9]

Cache performance information:
  com.evolveum.midpoint.provisioning.impl.ResourceCache:                               hits:  34504 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ArchetypeType:             hits:    498 ( 99.6%), weak hits:      0 (0.0%), misses:      2, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ObjectTemplateType:        hits:    498 ( 99.6%), weak hits:      0 (0.0%), misses:      2, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.RoleType:                  hits:  19507 ( 98.4%), weak hits:      0 (0.0%), misses:    327, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ShadowType:                hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:   6500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.SystemConfigurationType:   hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      1, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.UserType:                  hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:   1000, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalQueryCache.RoleType:                   hits:   9794 ( 97.9%), weak hits:      0 (0.0%), misses:    206, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalQueryCache.ShadowType:                 hits:  18985 ( 95.7%), weak hits:      0 (0.0%), misses:    349, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalQueryCache.UserType:                   hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalVersionCache.ResourceType:             hits:   2992 ( 99.6%), weak hits:      0 (0.0%), misses:     12, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalVersionCache.SystemConfigurationType:  hits:    108 ( 98.2%), weak hits:      0 (0.0%), misses:      2, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ArchetypeType:               hits:   3000 ( 85.7%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ObjectTemplateType:          hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.RoleType:                    hits:  51166 ( 72.1%), weak hits:      0 (0.0%), misses:  19834, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ShadowType:                  hits:  22834 ( 77.8%), weak hits:      0 (0.0%), misses:   6500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.SystemConfigurationType:     hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      1
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.UserType:                    hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:   1000, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalQueryCache.RoleType:                     hits:  10000 ( 50.0%), weak hits:      0 (0.0%), misses:  10000, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalQueryCache.ShadowType:                   hits:    666 (  3.2%), weak hits:      0 (0.0%), misses:  19334, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalQueryCache.UserType:                     hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalVersionCache.ResourceType:               hits:  31500 ( 91.3%), weak hits:      0 (0.0%), misses:   3000, passes:      0, not available:      4
  com.evolveum.midpoint.repo.cache.local.LocalVersionCache.SystemConfigurationType:    hits:      1 (  0.9%), weak hits:      0 (0.0%), misses:    110, passes:      0, not available:      0

Methods performance information:
  class com.evolveum.midpoint.model.common.stringpolicy.ObjectValuePolicyEvaluator.validateValue:                                  count:   1500, total time:         1.1 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.common.operation.import.accountsFromResource.statistics:                                                   count:      1, total time:         0.0 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.AbstractValueTransformationExpressionEvaluator.evaluate:  count: 277500, total time:     67362.1 ms [min:       0.0, max:     255.0, avg:       0.2]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.SingleShotEvaluation.evaluateExpression:                  count: 175500, total time:      2879.9 ms [min:       0.0, max:      65.8, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.ValueTupleTransformation.evaluate:                        count: 321000, total time:     38285.4 ms [min:       0.0, max:     248.7, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpression.evaluate:                                                  count: 475500, total time:      5453.1 ms [min:       0.0, max:     246.6, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpressionFactory.searchCustomFunctions:                              count:      1, total time:        13.8 ms [min:      13.8, max:      13.8, avg:      13.8]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluate:                                                                 count: 145500, total time:    115034.5 ms [min:       0.0, max:     280.3, avg:       0.8]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluatePrepared:                                                         count: 145500, total time:     86023.5 ms [min:       0.0, max:     280.1, avg:       0.6]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.prepare:                                                                  count: 145500, total time:     27522.8 ms [min:       0.0, max:      49.7, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute:                                                                    count:   1000, total time:    280435.8 ms [min:       0.1, max:    1542.8, avg:     280.4]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.focus.UserType:                                                     count:   1000, total time:     22360.0 ms [min:       0.1, max:     163.9, avg:      22.4]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.projection.ShadowType:                                              count:   3000, total time:     32493.6 ms [min:       5.1, max:      68.4, avg:      10.8]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.executeDelta:                                                               count:   3500, total time:     47613.9 ms [min:       5.0, max:     124.6, avg:      13.6]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.linkShadow:                                                                 count:   3000, total time:    193691.0 ms [min:      41.0, max:     794.8, avg:      64.6]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.updateSituationInShadow:                                                    count:   3000, total time:     31124.2 ms [min:       6.4, max:      89.2, avg:      10.4]
  com.evolveum.midpoint.model.impl.lens.Clockwork.authorizeRequest:                                                                count:    500, total time:        15.8 ms [min:       0.0, max:       2.6, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.Clockwork.click:                                                                           count:   2500, total time:    707342.0 ms [min:       3.9, max:    1867.8, avg:     282.9]
  com.evolveum.midpoint.model.impl.lens.Clockwork.execution:                                                                       count:   1000, total time:    280444.2 ms [min:       0.2, max:    1542.8, avg:     280.4]
  com.evolveum.midpoint.model.impl.lens.Clockwork.run:                                                                             count:    500, total time:    707499.3 ms [min:    1085.7, max:    4075.0, avg:    1415.0]
  com.evolveum.midpoint.model.impl.lens.IvwoConsolidator.consolidateToDelta:                                                       count: 120000, total time:      3494.0 ms [min:       0.0, max:      70.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.assignments.AssignmentEvaluator.evaluate:                                                  count:  21000, total time:     11226.7 ms [min:       0.2, max:      37.9, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.assignments.PathSegmentEvaluation.evaluate:                                                count: 147000, total time:     21274.7 ms [min:       0.0, max:      37.9, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.AssignedResourceObjectConstruction.evaluate:                                  count:  45000, total time:     30325.1 ms [min:       0.0, max:      99.0, avg:       0.7]
  com.evolveum.midpoint.model.impl.lens.construction.EvaluatedResourceObjectConstructionImpl.evaluate:                             count:  48000, total time:     30241.1 ms [min:       0.0, max:      98.6, avg:       0.6]
  com.evolveum.midpoint.model.impl.lens.construction.PlainResourceObjectConstruction.evaluate:                                     count:   3000, total time:       672.3 ms [min:       0.0, max:      16.3, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.ActivationProcessor.projectionActivation:                                        count:   6000, total time:       291.2 ms [min:       0.0, max:      28.9, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateItem:                                          count:  15000, total time:      1426.4 ms [min:       0.0, max:      70.3, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateValues:                                        count:   3000, total time:     10290.8 ms [min:       0.8, max:      74.2, avg:       3.4]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.determineFocusContext:                                             count:   3500, total time:     34736.3 ms [min:       0.0, max:     105.5, avg:       9.9]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.load:                                                              count:   1000, total time:      7102.7 ms [min:       0.1, max:      63.2, avg:       7.1]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.loadProjection:                                                    count:   3500, total time:      7031.2 ms [min:       0.0, max:      38.1, avg:       2.0]
  com.evolveum.midpoint.model.impl.lens.projector.DependencyProcessor.sortProjectionsToWaves:                                      count:   1000, total time:        10.0 ms [min:       0.0, max:       1.9, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ProjectionValuesProcessor.iteration:                                             count:   3000, total time:     28785.4 ms [min:       3.2, max:      94.9, avg:       9.6]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.activation:                                                            count:   1000, total time:       409.7 ms [min:       0.0, max:      30.0, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignments:                                                           count:   1000, total time:     54506.2 ms [min:      11.2, max:     508.4, avg:      54.5]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsConflicts:                                                  count:   1000, total time:        12.4 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsMembershipAndDelegate:                                      count:   1000, total time:      2407.3 ms [min:       0.3, max:      33.8, avg:       2.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsOrg:                                                        count:   1000, total time:        15.7 ms [min:       0.0, max:       2.8, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focus:                                                                 count:   1000, total time:    258631.7 ms [min:      72.9, max:    1655.0, avg:     258.6]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusActivation:                                                       count:   3000, total time:      1311.7 ms [min:       0.0, max:      31.1, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusCredentials:                                                      count:   1000, total time:        47.1 ms [min:       0.0, max:      18.9, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusLifecycle:                                                        count:   1000, total time:         2.2 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusPolicyRules:                                                      count:   1000, total time:        23.7 ms [min:       0.0, max:       0.6, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.inbound:                                                               count:   1000, total time:    191322.8 ms [min:      58.8, max:    1144.2, avg:     191.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.load:                                                                  count:   1000, total time:      7129.8 ms [min:       0.1, max:      63.3, avg:       7.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterAssignments:                                        count:   1000, total time:      2234.2 ms [min:       0.2, max:      35.5, avg:       2.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterProjections:                                        count:   1000, total time:      2298.3 ms [min:       0.2, max:      73.2, avg:       2.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateBeforeAssignments:                                       count:   1000, total time:      1906.8 ms [min:       0.0, max:      35.0, avg:       1.9]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.project:                                                               count:   1000, total time:    304082.1 ms [min:      82.4, max:    1864.8, avg:     304.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectProjection:                                                     count:   3000, total time:     35493.5 ms [min:       3.7, max:     111.3, avg:      11.8]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projection:                                                            count:   6000, total time:     35523.4 ms [min:       0.0, max:     111.3, avg:       5.9]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionCredentials:                                                 count:   3000, total time:      1340.1 ms [min:       0.0, max:      32.2, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionLifecycle:                                                   count:   3000, total time:      1263.0 ms [min:       0.0, max:      50.9, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionReconciliation:                                              count:   3000, total time:      1199.4 ms [min:       0.0, max:      37.5, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValues:                                                      count:   3000, total time:     30063.2 ms [min:       3.3, max:      95.5, avg:      10.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValuesPostRecon:                                             count:   3000, total time:      1411.2 ms [min:       0.1, max:      33.5, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.distributeConstructions:                               count:   1000, total time:        70.4 ms [min:       0.0, max:       1.2, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.evaluateFocusMappings:                                 count:   1000, total time:      1309.5 ms [min:       0.0, max:      24.6, avg:       1.3]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processAssignments:                                    count:   1000, total time:     54498.2 ms [min:      11.2, max:     508.4, avg:      54.5]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processProjections:                                    count:   1000, total time:     31834.2 ms [min:       1.0, max:     414.4, avg:      31.8]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentTripleEvaluator.evaluateAssignment:                              count:  21000, total time:     11577.2 ms [min:       0.2, max:      37.9, avg:       0.6]
  com.evolveum.midpoint.model.impl.lens.projector.focus.PruningOperation.execute:                                                  count:   1000, total time:        35.3 ms [min:       0.0, max:      23.7, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.consolidation.DeltaSetTripleMapConsolidation.consolidate:                  count:   1000, total time:      3787.5 ms [min:       1.9, max:      49.1, avg:       3.8]
  com.evolveum.midpoint.model.impl.lens.projector.policy.PolicyRuleEnforcer.execute:                                               count:    500, total time:         9.0 ms [min:       0.0, max:       0.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.notifyChange:                                                   count:   1000, total time:    714748.5 ms [min:       3.0, max:    4158.5, avg:     714.7]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.setupSituation:                                                 count:    500, total time:      1176.0 ms [min:       1.0, max:      47.8, avg:       2.4]
  com.evolveum.midpoint.model.impl.sync.SynchronizeAccountResultHandler.handle:                                                    count:    500, total time:    713068.7 ms [min:    1093.2, max:    4161.2, avg:    1426.1]
  com.evolveum.midpoint.model.impl.util.AuditHelper.audit:                                                                         count:   1000, total time:     81469.0 ms [min:       1.6, max:     352.5, avg:      81.5]
  com.evolveum.midpoint.model.impl.util.AuditHelper.resolveName:                                                                   count:  35334, total time:       674.5 ms [min:       0.0, max:       4.4, avg:       0.0]
  com.evolveum.midpoint.notifications.impl.NotificationHook.invoke:                                                                count:   2000, total time:         2.2 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.addObject:                                                            count:   2500, total time:     25738.2 ms [min:       4.8, max:      67.2, avg:      10.3]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.countObjects:                                                         count:      1, total time:         0.5 ms [min:       0.5, max:       0.5, avg:       0.5]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.getObject:                                                            count:   8502, total time:     17712.5 ms [min:       0.0, max:      47.0, avg:       2.1]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.modifyObject:                                                         count:   3500, total time:     24261.0 ms [min:       3.9, max:      85.9, avg:       6.9]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjects:                                                        count:  20000, total time:      7443.4 ms [min:       0.2, max:      45.9, avg:       0.4]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjectsIterative:                                               count:      1, total time:    115985.3 ms [min:  115985.3, max:  115985.3, avg:  115985.3]
  com.evolveum.midpoint.provisioning.impl.AccessChecker.accessCheck:                                                               count:   6500, total time:       146.1 ms [min:       0.0, max:       2.1, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ProvisioningContext.getConnectorInstance:                                                count:  10001, total time:       170.7 ms [min:       0.0, max:      25.3, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ResourceObjectConverter.addResourceObject:                                               count:   2500, total time:      5621.7 ms [min:       0.5, max:      39.5, avg:       2.2]
  com.evolveum.midpoint.provisioning.impl.ResourceObjectConverter.modifyEntitlement:                                               count:  19334, total time:      3363.0 ms [min:       0.1, max:      36.6, avg:       0.2]
  com.evolveum.midpoint.provisioning.impl.ShadowCache.handleObject:                                                                count:  20000, total time:       479.5 ms [min:       0.0, max:      17.0, avg:       0.0]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.addObject:                                                          count:   2500, total time:       812.2 ms [min:       0.1, max:      21.6, avg:       0.3]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.modifyObject:                                                       count:  19334, total time:      2680.9 ms [min:       0.0, max:      36.5, avg:       0.1]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.search:                                                             count:      1, total time:    115980.7 ms [min:  115980.7, max:  115980.7, avg:  115980.7]
  com.evolveum.midpoint.provisioning.ucf.impl.connid.ConnIdConvertor.convertToResourceObject:                                      count:    500, total time:       304.0 ms [min:       0.3, max:      24.7, avg:       0.6]
  com.evolveum.midpoint.repo.api.RepositoryService.addObject:                                                                      count:   3500, total time:     36690.0 ms [min:       2.6, max:     123.0, avg:      10.5]
  com.evolveum.midpoint.repo.api.RepositoryService.getObject:                                                                      count:   9340, total time:     40522.6 ms [min:       0.4, max:     101.5, avg:       4.3]
  com.evolveum.midpoint.repo.api.RepositoryService.getVersion:                                                                     count:     14, total time:         7.2 ms [min:       0.4, max:       0.6, avg:       0.5]
  com.evolveum.midpoint.repo.api.RepositoryService.modifyObject:                                                                   count:   7544, total time:    210710.0 ms [min:       2.8, max:     180.4, avg:      27.9]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjects:                                                                  count:   3056, total time:      9473.9 ms [min:       0.3, max:      43.9, avg:       3.1]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjectsIterative:                                                         count:      1, total time:        13.5 ms [min:      13.5, max:      13.5, avg:      13.5]
  com.evolveum.midpoint.repo.api.RepositoryService.searchShadowOwner:                                                              count:    500, total time:       475.6 ms [min:       0.3, max:      45.5, avg:       1.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.addObject:                                                                      count:   3500, total time:     37940.5 ms [min:       2.8, max:     123.6, avg:      10.8]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getObject:                                                                      count: 105335, total time:     54631.1 ms [min:       0.0, max:     101.5, avg:       0.5]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getVersion:                                                                     count:  34615, total time:       218.5 ms [min:       0.0, max:       1.2, avg:       0.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.invalidateCacheEntries:                                                         count:  11000, total time:      2593.7 ms [min:       0.0, max:      31.1, avg:       0.2]
  com.evolveum.midpoint.repo.cache.RepositoryCache.modifyObject:                                                                   count:   7500, total time:    220408.2 ms [min:       3.0, max:     794.7, avg:      29.4]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchObjects:                                                                  count:  41000, total time:      7745.2 ms [min:       0.0, max:     110.7, avg:       0.2]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchShadowOwner:                                                              count:    500, total time:       484.9 ms [min:       0.3, max:      45.5, avg:       1.0]
  com.evolveum.midpoint.schema.result.searchResult:                                                                                count:    500, total time:    115474.6 ms [min:      10.4, max:    3037.5, avg:     230.9]
  com.evolveum.midpoint.task.api.Task.getPathToRootTask:                                                                           count:   1500, total time:      8104.8 ms [min:       2.9, max:      54.0, avg:       5.4]
  com.evolveum.midpoint.task.api.TaskManager.createTaskInstance:                                                                   count:   1504, total time:      1139.5 ms [min:       0.4, max:      33.4, avg:       0.8]
  com.evolveum.midpoint.task.api.TaskManager.getTaskByIdentifier:                                                                  count:   1500, total time:      8091.7 ms [min:       2.9, max:      54.0, avg:       5.4]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskPlain:                                                        count:      4, total time:        16.0 ms [min:       2.3, max:       6.7, avg:       4.0]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskTypeByIdentifier:                                             count:   1500, total time:      6939.8 ms [min:       2.0, max:      53.1, avg:       4.6]
  com.evolveum.midpoint.wf.impl.hook.WfHook.invoke:                                                                                count:   2000, total time:        45.4 ms [min:       0.0, max:       0.8, avg:       0.0]
  com.evolveum.midpoint.wf.impl.processors.primary.PrimaryChangeProcessor.previewOrProcessModelInvocation:                         count:    500, total time:         6.8 ms [min:       0.0, max:       0.4, avg:       0.0]
  interface com.evolveum.midpoint.notifications.api.NotificationManager.processEvent:                                              count:      1, total time:         6.0 ms [min:       6.0, max:       6.0, avg:       6.0]
  org.identityconnectors.framework.api.ConnectorFacade.create:                                                                     count:   2500, total time:       682.8 ms [min:       0.1, max:      21.5, avg:       0.3]
  org.identityconnectors.framework.api.ConnectorFacade.search:                                                                     count:      1, total time:    115978.8 ms [min:  115978.8, max:  115978.8, avg:  115978.8]
  org.identityconnectors.framework.api.ConnectorFacade.updateDelta:                                                                count:  19334, total time:      1851.3 ms [min:       0.0, max:      31.7, avg:       0.1]



********** no-op-run-1-of-task-import-000 FINISHED **********

Iterative task information:
  Successfully processed:    500 in     254037 ms =    508.1 ms per object, last: ShadowType:u-00000499 (u-00000499 (ACCOUNT - default - AccountObjectClass), d3c82763-1bee-47e0-a791-e43a95c733c2) on Tue May 04 04:57:48 CEST 2021 in 391 ms
  Failed:                      0 in          0 ms =      0.0 ms per object

Actions executed:
  All object actions:
    MODIFY     ShadowType                     http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:    500 time(s), last: u-00000499 (Shadow u-00000499 (ACCOUNT - default - AccountObjectClass), d3c82763-1bee-47e0-a791-e43a95c733c2) on Tue May 04 04:57:48 CEST 2021
  Resulting object actions:
    MODIFY     ShadowType                     http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:    500 time(s), last: u-00000499 (Shadow u-00000499 (ACCOUNT - default - AccountObjectClass), d3c82763-1bee-47e0-a791-e43a95c733c2) on Tue May 04 04:57:48 CEST 2021

Environmental performance information:
  Provisioning:
    source-000 (AccountObjectClass)                              count:        1, total time:       95 ms [min:      0, max:      1, avg:    95.0] GET:     0:  0 SEARCH:     1:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
  Mappings:
    RoleType (aggregated)                    count:  40000, total time:  17844 ms [min:     0, max:    39, avg:     0.4]
    target-002                               count:    500, total time:     53 ms [min:     0, max:     1, avg:     0.1]
    target-000                               count:    500, total time:     50 ms [min:     0, max:     1, avg:     0.1]
    target-001                               count:    500, total time:     55 ms [min:     0, max:     1, avg:     0.1]
    source-000                               count: 103000, total time:  87923 ms [min:     0, max:    50, avg:     0.9]
    target-004                               count:    500, total time:     60 ms [min:     0, max:     1, avg:     0.1]
    target-003                               count:    500, total time:     55 ms [min:     0, max:     1, avg:     0.1]
  Last message: Successfully finished evaluation of mapping mapping in inbound expression for a-multi-0049 in resource:3d183de1-9a29-4b48-9302-1917c8c0d7f3(source-000) in 0 ms.
  On:           2021-05-04T04:57:48.568+02:00

Repository performance information:
  getObject.ArchetypeType:      count:      1, total time:        0 ms [min:     0, max:     0, avg:     0.0]
  getObject.RoleType:           count:     71, total time:       64 ms [min:     0, max:     2, avg:     0.9]
  getObject.ShadowType:         count:   2385, total time:     1332 ms [min:     0, max:    11, avg:     0.6]
  getObject.TaskType:           count:      4, total time:       12 ms [min:     1, max:     8, avg:     3.0]
  getObject.UserType:           count:   2004, total time:    15164 ms [min:     0, max:    46, avg:     7.6]
  getVersion.ResourceType:      count:      6, total time:        3 ms [min:     0, max:     1, avg:     0.5]
  modifyObject.ShadowType:      count:    500, total time:     2303 ms [min:     3, max:    34, avg:     4.6], wasted time for    1 retry/retries:       30 ms [min:     0, max:    30, avg:     0.1] (  1.30%)
  modifyObject.TaskType:        count:     20, total time:      338 ms [min:     4, max:    31, avg:    16.9]
  searchObjects.RoleType:       count:    100, total time:       82 ms [min:     0, max:     2, avg:     0.8]
  searchObjects.ShadowType:     count:    671, total time:      687 ms [min:     0, max:    12, avg:     1.0]
  searchObjects.TaskType:       count:   1500, total time:     3854 ms [min:     0, max:    20, avg:     2.6]
  searchShadowOwner.FocusType:  count:    500, total time:    14602 ms [min:    21, max:    74, avg:    29.2]

Cache performance information:
  com.evolveum.midpoint.provisioning.impl.ResourceCache:                               hits:  28004 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ArchetypeType:             hits:    499 ( 99.8%), weak hits:      0 (0.0%), misses:      1, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ObjectTemplateType:        hits:    500 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.RoleType:                  hits:  19763 ( 99.6%), weak hits:      0 (0.0%), misses:     71, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ShadowType:                hits:    615 ( 20.5%), weak hits:      0 (0.0%), misses:   2385, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.SystemConfigurationType:   hits:      1 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.UserType:                  hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalQueryCache.RoleType:                   hits:   9900 ( 99.0%), weak hits:      0 (0.0%), misses:    100, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalQueryCache.ShadowType:                 hits:  19163 ( 96.6%), weak hits:      0 (0.0%), misses:    171, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalVersionCache.ResourceType:             hits:   2998 ( 99.8%), weak hits:      0 (0.0%), misses:      6, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalVersionCache.SystemConfigurationType:  hits:     41 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ArchetypeType:               hits:   3000 ( 85.7%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ObjectTemplateType:          hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.RoleType:                    hits:  41166 ( 67.5%), weak hits:      0 (0.0%), misses:  19834, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ShadowType:                  hits:   2500 ( 45.5%), weak hits:      0 (0.0%), misses:   3000, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.SystemConfigurationType:     hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      1
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.UserType:                    hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalQueryCache.RoleType:                     hits:  10000 ( 50.0%), weak hits:      0 (0.0%), misses:  10000, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalQueryCache.ShadowType:                   hits:    666 (  3.2%), weak hits:      0 (0.0%), misses:  19334, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalVersionCache.ResourceType:               hits:  25000 ( 89.3%), weak hits:      0 (0.0%), misses:   3000, passes:      0, not available:      4
  com.evolveum.midpoint.repo.cache.local.LocalVersionCache.SystemConfigurationType:    hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:     41, passes:      0, not available:      0

Methods performance information:
  com.evolveum.midpoint.common.operation.import.accountsFromResource.statistics:                                                   count:      1, total time:         0.0 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.AbstractValueTransformationExpressionEvaluator.evaluate:  count: 413000, total time:     63053.1 ms [min:       0.0, max:      45.4, avg:       0.2]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.SingleShotEvaluation.evaluateExpression:                  count: 331000, total time:      3751.1 ms [min:       0.0, max:      21.5, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.ValueTupleTransformation.evaluate:                        count: 321000, total time:     31903.1 ms [min:       0.0, max:      33.3, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpression.evaluate:                                                  count: 631000, total time:      3803.9 ms [min:       0.0, max:      32.7, avg:       0.0]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluate:                                                                 count: 145500, total time:    105117.1 ms [min:       0.0, max:      49.8, avg:       0.7]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluatePrepared:                                                         count: 145500, total time:     79009.9 ms [min:       0.0, max:      46.6, avg:       0.5]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.prepare:                                                                  count: 145500, total time:     24842.8 ms [min:       0.0, max:      24.6, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute:                                                                    count:   1000, total time:      1238.0 ms [min:       0.0, max:       3.3, avg:       1.2]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.projection.ShadowType:                                              count:   3000, total time:      1211.9 ms [min:       0.0, max:       2.8, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.updateSituationInShadow:                                                    count:   3000, total time:      1188.2 ms [min:       0.0, max:       2.8, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.Clockwork.authorizeRequest:                                                                count:    500, total time:         0.9 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.Clockwork.click:                                                                           count:   2500, total time:    225297.7 ms [min:       0.1, max:     417.3, avg:      90.1]
  com.evolveum.midpoint.model.impl.lens.Clockwork.execution:                                                                       count:   1000, total time:      1241.9 ms [min:       0.0, max:       3.3, avg:       1.2]
  com.evolveum.midpoint.model.impl.lens.Clockwork.run:                                                                             count:    500, total time:    225391.7 ms [min:     339.6, max:     527.0, avg:     450.8]
  com.evolveum.midpoint.model.impl.lens.IvwoConsolidator.consolidateToDelta:                                                       count: 112500, total time:      2165.0 ms [min:       0.0, max:      18.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.assignments.AssignmentEvaluator.evaluate:                                                  count:  21000, total time:      9063.3 ms [min:       0.1, max:      21.3, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.assignments.PathSegmentEvaluation.evaluate:                                                count: 147000, total time:     17200.3 ms [min:       0.0, max:      21.3, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.AssignedResourceObjectConstruction.evaluate:                                  count:  45000, total time:     28979.2 ms [min:       0.0, max:      40.0, avg:       0.6]
  com.evolveum.midpoint.model.impl.lens.construction.EvaluatedResourceObjectConstructionImpl.evaluate:                             count:  48000, total time:     29336.3 ms [min:       0.0, max:      40.0, avg:       0.6]
  com.evolveum.midpoint.model.impl.lens.construction.PlainResourceObjectConstruction.evaluate:                                     count:   3000, total time:       652.3 ms [min:       0.0, max:      15.6, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.ActivationProcessor.projectionActivation:                                        count:   6000, total time:        25.4 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateItem:                                          count:   7500, total time:       158.0 ms [min:       0.0, max:       1.8, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateValues:                                        count:   3000, total time:       505.3 ms [min:       0.0, max:       5.6, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.determineFocusContext:                                             count:   3000, total time:     14411.9 ms [min:       0.0, max:      46.0, avg:       4.8]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.load:                                                              count:    500, total time:      5371.6 ms [min:       1.2, max:      30.9, avg:      10.7]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.loadProjection:                                                    count:   3000, total time:       204.0 ms [min:       0.0, max:       0.1, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.DependencyProcessor.sortProjectionsToWaves:                                      count:   1000, total time:         2.1 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ProjectionValuesProcessor.iteration:                                             count:   3000, total time:      1221.9 ms [min:       0.0, max:      16.0, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.activation:                                                            count:   1000, total time:       287.5 ms [min:       0.0, max:       0.9, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignments:                                                           count:   1000, total time:     42254.6 ms [min:       8.5, max:     134.9, avg:      42.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsConflicts:                                                  count:   1000, total time:         6.8 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsMembershipAndDelegate:                                      count:   1000, total time:       110.1 ms [min:       0.1, max:       0.7, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsOrg:                                                        count:   1000, total time:        11.6 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focus:                                                                 count:   1000, total time:    200512.9 ms [min:      65.1, max:     367.5, avg:     200.5]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusActivation:                                                       count:   3000, total time:        98.2 ms [min:       0.0, max:       0.2, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusCredentials:                                                      count:   1000, total time:         6.4 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusLifecycle:                                                        count:   1000, total time:         1.9 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusPolicyRules:                                                      count:   1000, total time:        13.5 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.inbound:                                                               count:   1000, total time:    157876.6 ms [min:      56.4, max:     278.9, avg:     157.9]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.load:                                                                  count:    500, total time:      5374.9 ms [min:       1.2, max:      30.9, avg:      10.7]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterAssignments:                                        count:   1000, total time:        19.3 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterProjections:                                        count:   1000, total time:        13.9 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateBeforeAssignments:                                       count:   1000, total time:        20.7 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.project:                                                               count:   1000, total time:    207716.5 ms [min:      65.2, max:     388.3, avg:     207.7]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectProjection:                                                     count:   3000, total time:      1449.4 ms [min:       0.2, max:      16.0, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projection:                                                            count:   6000, total time:      1477.8 ms [min:       0.0, max:      16.0, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionCredentials:                                                 count:   3000, total time:        28.4 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionLifecycle:                                                   count:   3000, total time:         6.1 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionReconciliation:                                              count:   3000, total time:        97.2 ms [min:       0.0, max:       0.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValues:                                                      count:   3000, total time:      1249.6 ms [min:       0.0, max:      16.0, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValuesPostRecon:                                             count:   3000, total time:         2.4 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.distributeConstructions:                               count:   1000, total time:        59.5 ms [min:       0.0, max:       0.7, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.evaluateFocusMappings:                                 count:   1000, total time:        10.3 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processAssignments:                                    count:   1000, total time:     42248.6 ms [min:       8.5, max:     134.9, avg:      42.2]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processProjections:                                    count:   1000, total time:     29174.7 ms [min:       0.2, max:     117.5, avg:      29.2]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentTripleEvaluator.evaluateAssignment:                              count:  21000, total time:      9355.8 ms [min:       0.2, max:      21.4, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.focus.PruningOperation.execute:                                                  count:   1000, total time:         3.8 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.consolidation.DeltaSetTripleMapConsolidation.consolidate:                  count:   1000, total time:      3454.3 ms [min:       2.2, max:      20.6, avg:       3.5]
  com.evolveum.midpoint.model.impl.lens.projector.policy.PolicyRuleEnforcer.execute:                                               count:    500, total time:         6.7 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.notifyChange:                                                   count:    500, total time:    253536.6 ms [min:     389.6, max:     620.6, avg:     507.1]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.setupSituation:                                                 count:    500, total time:     25252.5 ms [min:      37.6, max:     108.9, avg:      50.5]
  com.evolveum.midpoint.model.impl.sync.SynchronizeAccountResultHandler.handle:                                                    count:    500, total time:    254044.7 ms [min:     390.2, max:     622.5, avg:     508.1]
  com.evolveum.midpoint.notifications.impl.NotificationHook.invoke:                                                                count:   2000, total time:         1.8 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.countObjects:                                                         count:      1, total time:         0.1 ms [min:       0.1, max:       0.1, avg:       0.1]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.getObject:                                                            count:   8002, total time:      5849.9 ms [min:       0.0, max:      19.1, avg:       0.7]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjects:                                                        count:  20000, total time:      5051.1 ms [min:       0.2, max:      36.2, avg:       0.3]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjectsIterative:                                               count:      1, total time:     41233.8 ms [min:   41233.8, max:   41233.8, avg:   41233.8]
  com.evolveum.midpoint.provisioning.impl.AccessChecker.accessCheck:                                                               count:    500, total time:       110.5 ms [min:       0.1, max:       0.5, avg:       0.2]
  com.evolveum.midpoint.provisioning.impl.ProvisioningContext.getConnectorInstance:                                                count:      1, total time:         0.0 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ShadowCache.handleObject:                                                                count:  20000, total time:       374.2 ms [min:       0.0, max:       0.2, avg:       0.0]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.search:                                                             count:      1, total time:     41233.4 ms [min:   41233.4, max:   41233.4, avg:   41233.4]
  com.evolveum.midpoint.provisioning.ucf.impl.connid.ConnIdConvertor.convertToResourceObject:                                      count:    500, total time:       231.5 ms [min:       0.3, max:      14.3, avg:       0.5]
  com.evolveum.midpoint.repo.api.RepositoryService.getObject:                                                                      count:   4465, total time:     16541.1 ms [min:       0.3, max:      46.0, avg:       3.7]
  com.evolveum.midpoint.repo.api.RepositoryService.getVersion:                                                                     count:      6, total time:         2.5 ms [min:       0.4, max:       0.5, avg:       0.4]
  com.evolveum.midpoint.repo.api.RepositoryService.modifyObject:                                                                   count:    520, total time:      2618.5 ms [min:       3.5, max:      31.3, avg:       5.0]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjects:                                                                  count:   2271, total time:      4634.3 ms [min:       0.5, max:      19.7, avg:       2.0]
  com.evolveum.midpoint.repo.api.RepositoryService.searchShadowOwner:                                                              count:    500, total time:     14592.2 ms [min:      21.5, max:      73.6, avg:      29.2]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getObject:                                                                      count:  71001, total time:     22225.7 ms [min:       0.0, max:      46.0, avg:       0.3]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getVersion:                                                                     count:  28045, total time:       111.1 ms [min:       0.0, max:       0.6, avg:       0.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.invalidateCacheEntries:                                                         count:    500, total time:       109.7 ms [min:       0.2, max:       0.3, avg:       0.2]
  com.evolveum.midpoint.repo.cache.RepositoryCache.modifyObject:                                                                   count:    500, total time:      2430.6 ms [min:       3.7, max:      34.2, avg:       4.9]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchObjects:                                                                  count:  40500, total time:      4572.7 ms [min:       0.0, max:      36.1, avg:       0.1]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchShadowOwner:                                                              count:    500, total time:     25244.9 ms [min:      37.6, max:     108.9, avg:      50.5]
  com.evolveum.midpoint.schema.result.searchResult:                                                                                count:    500, total time:     40868.2 ms [min:       1.9, max:     491.7, avg:      81.7]
  com.evolveum.midpoint.task.api.Task.getPathToRootTask:                                                                           count:   1500, total time:      4881.8 ms [min:       1.2, max:      20.5, avg:       3.3]
  com.evolveum.midpoint.task.api.TaskManager.createTaskInstance:                                                                   count:   1504, total time:       811.1 ms [min:       0.4, max:      11.5, avg:       0.5]
  com.evolveum.midpoint.task.api.TaskManager.getTaskByIdentifier:                                                                  count:   1500, total time:      4872.6 ms [min:       1.2, max:      20.5, avg:       3.2]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskPlain:                                                        count:      4, total time:        15.9 ms [min:       1.3, max:       9.6, avg:       4.0]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskTypeByIdentifier:                                             count:   1500, total time:      4051.9 ms [min:       0.8, max:      19.9, avg:       2.7]
  com.evolveum.midpoint.wf.impl.hook.WfHook.invoke:                                                                                count:   2000, total time:        25.4 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.wf.impl.processors.primary.PrimaryChangeProcessor.previewOrProcessModelInvocation:                         count:    500, total time:         1.4 ms [min:       0.0, max:       0.0, avg:       0.0]
  interface com.evolveum.midpoint.notifications.api.NotificationManager.processEvent:                                              count:      1, total time:         0.1 ms [min:       0.1, max:       0.1, avg:       0.1]
  org.identityconnectors.framework.api.ConnectorFacade.search:                                                                     count:      1, total time:     41232.8 ms [min:   41232.8, max:   41232.8, avg:   41232.8]



********** task-recomputation FINISHED **********

Iterative task information:
  Successfully processed:    500 in     246798 ms =    493.6 ms per object, last: null:u-00000061 (User null (u-00000061), ffc0b03e-63fa-4254-8a56-613279e9717e) on Tue May 04 04:58:33 CEST 2021 in 343 ms
  Failed:                      0 in          0 ms =      0.0 ms per object

Actions executed:
  All object actions:
  Resulting object actions:

Environmental performance information:
  Provisioning:
    target-000 (GroupObjectClass)                                count:     1000, total time:     1953 ms [min:      0, max:     40, avg:     2.0] GET:     0:  0 SEARCH:  1000:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-003 (GroupObjectClass)                                count:     1000, total time:     1998 ms [min:      0, max:     34, avg:     2.0] GET:     0:  0 SEARCH:  1000:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-003 (AccountObjectClass)                              count:     1000, total time:      181 ms [min:      0, max:     16, avg:     0.2] GET:  1000:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    source-000 (AccountObjectClass)                              count:     1000, total time:      727 ms [min:      0, max:     12, avg:     0.7] GET:  1000:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-001 (AccountObjectClass)                              count:     1000, total time:      176 ms [min:      0, max:      4, avg:     0.2] GET:  1000:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-004 (AccountObjectClass)                              count:     1000, total time:      170 ms [min:      0, max:      1, avg:     0.2] GET:  1000:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-004 (GroupObjectClass)                                count:     1000, total time:     1765 ms [min:      0, max:     32, avg:     1.8] GET:     0:  0 SEARCH:  1000:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-002 (GroupObjectClass)                                count:     1000, total time:     1835 ms [min:      0, max:     42, avg:     1.8] GET:     0:  0 SEARCH:  1000:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-001 (GroupObjectClass)                                count:     1000, total time:     1674 ms [min:      0, max:     31, avg:     1.7] GET:     0:  0 SEARCH:  1000:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-002 (AccountObjectClass)                              count:     1000, total time:      167 ms [min:      0, max:      9, avg:     0.2] GET:  1000:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-000 (AccountObjectClass)                              count:     1000, total time:      164 ms [min:      0, max:     13, avg:     0.2] GET:  1000:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
  Mappings:
    RoleType (aggregated)                    count:  40000, total time:  18942 ms [min:     0, max:    34, avg:     0.5]
    target-002                               count:    500, total time:     74 ms [min:     0, max:     1, avg:     0.1]
    target-000                               count:    500, total time:     59 ms [min:     0, max:     1, avg:     0.1]
    target-001                               count:    500, total time:     52 ms [min:     0, max:     2, avg:     0.1]
    source-000                               count: 103000, total time:  65353 ms [min:     0, max:    44, avg:     0.6]
    target-004                               count:    500, total time:     55 ms [min:     0, max:     1, avg:     0.1]
    target-003                               count:    500, total time:     70 ms [min:     0, max:     1, avg:     0.1]
  Last message: Successfully finished evaluation of mapping mapping in inbound expression for a-multi-0049 in resource:3d183de1-9a29-4b48-9302-1917c8c0d7f3(source-000) in 1 ms.
  On:           2021-05-04T04:58:33.967+02:00

Repository performance information:
  countObjects.UserType:               count:      1, total time:       10 ms [min:    10, max:    10, avg:    10.0]
  getObject.ArchetypeType:             count:      1, total time:        1 ms [min:     1, max:     1, avg:     1.0]
  getObject.ObjectTemplateType:        count:      1, total time:        1 ms [min:     1, max:     1, avg:     1.0]
  getObject.RoleType:                  count:    118, total time:      137 ms [min:     0, max:    11, avg:     1.2]
  getObject.ShadowType:                count:   1204, total time:      781 ms [min:     0, max:    16, avg:     0.6]
  getObject.SystemConfigurationType:   count:      1, total time:        2 ms [min:     2, max:     2, avg:     2.0]
  getObject.TaskType:                  count:      4, total time:       11 ms [min:     1, max:     7, avg:     2.8]
  getObject.UserType:                  count:   2004, total time:    15874 ms [min:     0, max:    44, avg:     7.9]
  getVersion.SystemConfigurationType:  count:      1, total time:        0 ms [min:     0, max:     0, avg:     0.0]
  modifyObject.TaskType:               count:     21, total time:      345 ms [min:     5, max:    32, avg:    16.4]
  searchObjects.RoleType:              count:    101, total time:       81 ms [min:     0, max:     5, avg:     0.8]
  searchObjects.ShadowType:            count:  38839, total time:    29122 ms [min:     0, max:    32, avg:     0.7]
  searchObjects.TaskType:              count:   1500, total time:     4688 ms [min:     0, max:    37, avg:     3.1]
  searchObjects.UserType:              count:     11, total time:    14416 ms [min:     2, max:  1657, avg:  1310.5]

Cache performance information:
  all.UserType:                                                                        hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.provisioning.impl.ResourceCache:                               hits:  44000 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ArchetypeType:             hits:    499 ( 99.8%), weak hits:      0 (0.0%), misses:      1, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ObjectTemplateType:        hits:    499 ( 99.8%), weak hits:      0 (0.0%), misses:      1, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.RoleType:                  hits:  19716 ( 99.4%), weak hits:      0 (0.0%), misses:    118, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ShadowType:                hits:   1796 ( 59.9%), weak hits:      0 (0.0%), misses:   1204, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.SystemConfigurationType:   hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      1, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.UserType:                  hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalQueryCache.RoleType:                   hits:   9899 ( 99.0%), weak hits:      0 (0.0%), misses:    101, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalQueryCache.ShadowType:                 hits:  19163 ( 33.0%), weak hits:      0 (0.0%), misses:    171, passes:  38668, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalQueryCache.UserType:                   hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:      1, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalVersionCache.ResourceType:             hits:   3000 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalVersionCache.SystemConfigurationType:  hits:     41 ( 97.6%), weak hits:      0 (0.0%), misses:      1, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ArchetypeType:               hits:   3000 ( 85.7%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ObjectTemplateType:          hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.RoleType:                    hits:  41166 ( 67.5%), weak hits:      0 (0.0%), misses:  19834, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ShadowType:                  hits:   6000 ( 66.7%), weak hits:      0 (0.0%), misses:   3000, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.SystemConfigurationType:     hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      1
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.UserType:                    hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalQueryCache.RoleType:                     hits:  10000 ( 50.0%), weak hits:      0 (0.0%), misses:  10000, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalQueryCache.ShadowType:                   hits:    666 (  1.1%), weak hits:      0 (0.0%), misses:  19334, passes:  38668, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalQueryCache.UserType:                     hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      1
  com.evolveum.midpoint.repo.cache.local.LocalVersionCache.ResourceType:               hits:  41000 ( 93.2%), weak hits:      0 (0.0%), misses:   3000, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalVersionCache.SystemConfigurationType:    hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:     42, passes:      0, not available:      0

Methods performance information:
  com.evolveum.midpoint.common.operation.recompute.statistics:                                                                     count:      1, total time:         0.0 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.AbstractValueTransformationExpressionEvaluator.evaluate:  count: 413000, total time:     65060.6 ms [min:       0.0, max:      42.4, avg:       0.2]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.SingleShotEvaluation.evaluateExpression:                  count: 331000, total time:      3964.7 ms [min:       0.0, max:      20.8, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.ValueTupleTransformation.evaluate:                        count: 321000, total time:     33833.1 ms [min:       0.0, max:      31.0, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpression.evaluate:                                                  count: 631000, total time:      3975.0 ms [min:       0.0, max:      20.8, avg:       0.0]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluate:                                                                 count: 145500, total time:     83444.3 ms [min:       0.0, max:      43.7, avg:       0.6]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluatePrepared:                                                         count: 145500, total time:     81589.7 ms [min:       0.0, max:      43.7, avg:       0.6]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.prepare:                                                                  count: 145500, total time:       651.2 ms [min:       0.0, max:       1.6, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute:                                                                    count:   1000, total time:       345.6 ms [min:       0.0, max:      29.2, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.projection.ShadowType:                                              count:   3000, total time:       321.3 ms [min:       0.1, max:      28.6, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.updateSituationInShadow:                                                    count:   3000, total time:       297.0 ms [min:       0.0, max:      28.6, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.Clockwork.authorizeRequest:                                                                count:    500, total time:         1.0 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.Clockwork.click:                                                                           count:   2500, total time:    246507.3 ms [min:       0.1, max:     695.8, avg:      98.6]
  com.evolveum.midpoint.model.impl.lens.Clockwork.execution:                                                                       count:   1000, total time:       349.4 ms [min:       0.0, max:      29.3, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.Clockwork.run:                                                                             count:    500, total time:    246597.0 ms [min:     342.1, max:     805.7, avg:     493.2]
  com.evolveum.midpoint.model.impl.lens.IvwoConsolidator.consolidateToDelta:                                                       count: 120000, total time:      2447.4 ms [min:       0.0, max:      17.9, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.assignments.AssignmentEvaluator.evaluate:                                                  count:  21000, total time:      9589.7 ms [min:       0.1, max:      27.0, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.assignments.PathSegmentEvaluation.evaluate:                                                count: 147000, total time:     18186.1 ms [min:       0.0, max:      27.0, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.AssignedResourceObjectConstruction.evaluate:                                  count:  45000, total time:     30698.6 ms [min:       0.0, max:      39.0, avg:       0.7]
  com.evolveum.midpoint.model.impl.lens.construction.EvaluatedResourceObjectConstructionImpl.evaluate:                             count:  48000, total time:     31092.7 ms [min:       0.0, max:      39.0, avg:       0.6]
  com.evolveum.midpoint.model.impl.lens.construction.PlainResourceObjectConstruction.evaluate:                                     count:   3000, total time:       678.6 ms [min:       0.0, max:       1.5, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.ActivationProcessor.projectionActivation:                                        count:   6000, total time:        20.2 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateItem:                                          count:  15000, total time:       433.1 ms [min:       0.0, max:       0.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateValues:                                        count:   3000, total time:       609.1 ms [min:       0.0, max:       0.5, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.determineFocusContext:                                             count:   3000, total time:     15031.2 ms [min:       0.0, max:      43.6, avg:       5.0]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.load:                                                              count:    500, total time:     76367.8 ms [min:     102.2, max:     367.9, avg:     152.7]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.loadProjection:                                                    count:   3000, total time:     36658.7 ms [min:       2.1, max:      67.0, avg:      12.2]
  com.evolveum.midpoint.model.impl.lens.projector.DependencyProcessor.sortProjectionsToWaves:                                      count:   1000, total time:         5.7 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ProjectionValuesProcessor.iteration:                                             count:   3000, total time:      1356.6 ms [min:       0.0, max:       1.9, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.activation:                                                            count:   1000, total time:        40.6 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignments:                                                           count:   1000, total time:     45094.0 ms [min:       8.6, max:     167.8, avg:      45.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsConflicts:                                                  count:   1000, total time:         6.8 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsMembershipAndDelegate:                                      count:   1000, total time:       112.2 ms [min:       0.1, max:       0.2, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsOrg:                                                        count:   1000, total time:         8.8 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focus:                                                                 count:   1000, total time:    149157.1 ms [min:      70.6, max:     297.8, avg:     149.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusActivation:                                                       count:   3000, total time:        98.7 ms [min:       0.0, max:       0.2, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusCredentials:                                                      count:   1000, total time:         5.0 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusLifecycle:                                                        count:   1000, total time:         1.9 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusPolicyRules:                                                      count:   1000, total time:        14.4 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.inbound:                                                               count:   1000, total time:    103665.1 ms [min:      61.1, max:     213.8, avg:     103.7]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.load:                                                                  count:    500, total time:     76370.8 ms [min:     102.2, max:     367.9, avg:     152.7]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterAssignments:                                        count:   1000, total time:        16.1 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterProjections:                                        count:   1000, total time:        12.8 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateBeforeAssignments:                                       count:   1000, total time:        16.8 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.project:                                                               count:   1000, total time:    228893.8 ms [min:      70.6, max:     667.3, avg:     228.9]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectProjection:                                                     count:   3000, total time:      3242.4 ms [min:       0.2, max:      18.2, avg:       1.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projection:                                                            count:   6000, total time:      3260.0 ms [min:       0.0, max:      18.2, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionCredentials:                                                 count:   3000, total time:        31.5 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionLifecycle:                                                   count:   3000, total time:         9.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionReconciliation:                                              count:   3000, total time:      1537.5 ms [min:       0.1, max:      17.6, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValues:                                                      count:   3000, total time:      1380.7 ms [min:       0.0, max:       1.9, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValuesPostRecon:                                             count:   3000, total time:       195.9 ms [min:       0.0, max:       0.4, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.distributeConstructions:                               count:   1000, total time:        52.9 ms [min:       0.0, max:       0.1, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.evaluateFocusMappings:                                 count:   1000, total time:         9.9 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processAssignments:                                    count:   1000, total time:     45087.9 ms [min:       8.6, max:     167.8, avg:      45.1]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processProjections:                                    count:   1000, total time:     30879.2 ms [min:       0.2, max:     145.4, avg:      30.9]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentTripleEvaluator.evaluateAssignment:                              count:  21000, total time:      9888.4 ms [min:       0.2, max:      27.0, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.projector.focus.PruningOperation.execute:                                                  count:   1000, total time:         3.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.consolidation.DeltaSetTripleMapConsolidation.consolidate:                  count:   1000, total time:      3539.5 ms [min:       2.2, max:      22.0, avg:       3.5]
  com.evolveum.midpoint.model.impl.lens.projector.policy.PolicyRuleEnforcer.execute:                                               count:    500, total time:         6.9 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.sync.RecomputeTaskHandler.handle:                                                               count:    500, total time:    246755.9 ms [min:     342.5, max:     806.1, avg:     493.5]
  com.evolveum.midpoint.notifications.impl.NotificationHook.invoke:                                                                count:   2000, total time:         1.8 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.getObject:                                                            count:  12000, total time:     74841.1 ms [min:       0.0, max:      66.6, avg:       6.2]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjects:                                                        count:  20000, total time:      5293.0 ms [min:       0.1, max:      30.0, avg:       0.3]
  com.evolveum.midpoint.provisioning.impl.AccessChecker.accessCheck:                                                               count:   6000, total time:       233.3 ms [min:       0.0, max:       9.4, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ProvisioningContext.getConnectorInstance:                                                count:   6000, total time:       149.2 ms [min:       0.0, max:       0.5, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ShadowCache.handleObject:                                                                count:  20000, total time:       394.9 ms [min:       0.0, max:      15.9, avg:       0.0]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.fetchObject:                                                        count:   6000, total time:      3453.0 ms [min:       0.2, max:      16.6, avg:       0.6]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.search:                                                             count:   5000, total time:     15467.6 ms [min:       1.0, max:      43.2, avg:       3.1]
  com.evolveum.midpoint.provisioning.ucf.impl.connid.ConnIdConvertor.convertToResourceObject:                                      count:  44668, total time:      2198.9 ms [min:       0.0, max:      15.8, avg:       0.0]
  com.evolveum.midpoint.repo.api.RepositoryService.countObjects:                                                                   count:      1, total time:        10.1 ms [min:      10.1, max:      10.1, avg:      10.1]
  com.evolveum.midpoint.repo.api.RepositoryService.getObject:                                                                      count:   3333, total time:     16803.3 ms [min:       0.3, max:      43.6, avg:       5.0]
  com.evolveum.midpoint.repo.api.RepositoryService.getVersion:                                                                     count:      1, total time:         0.5 ms [min:       0.5, max:       0.5, avg:       0.5]
  com.evolveum.midpoint.repo.api.RepositoryService.modifyObject:                                                                   count:     21, total time:       344.6 ms [min:       5.0, max:      32.4, avg:      16.4]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjects:                                                                  count:  40451, total time:     48342.3 ms [min:       0.4, max:    1657.8, avg:       1.2]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjectsIterative:                                                         count:      1, total time:     43345.5 ms [min:   43345.5, max:   43345.5, avg:   43345.5]
  com.evolveum.midpoint.repo.cache.RepositoryCache.countObjects:                                                                   count:      1, total time:        10.3 ms [min:      10.3, max:      10.3, avg:      10.3]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getObject:                                                                      count:  74501, total time:     21104.3 ms [min:       0.0, max:      43.6, avg:       0.3]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getVersion:                                                                     count:  44042, total time:       224.6 ms [min:       0.0, max:      20.9, avg:       0.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchObjects:                                                                  count:  78668, total time:     34739.9 ms [min:       0.0, max:      31.4, avg:       0.4]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchObjectsIterative:                                                         count:      1, total time:     43346.5 ms [min:   43346.5, max:   43346.5, avg:   43346.5]
  com.evolveum.midpoint.task.api.Task.getPathToRootTask:                                                                           count:   1500, total time:      5811.7 ms [min:       1.4, max:      50.5, avg:       3.9]
  com.evolveum.midpoint.task.api.TaskManager.createTaskInstance:                                                                   count:   1504, total time:       922.4 ms [min:       0.4, max:      20.7, avg:       0.6]
  com.evolveum.midpoint.task.api.TaskManager.getTaskByIdentifier:                                                                  count:   1500, total time:      5802.0 ms [min:       1.4, max:      50.5, avg:       3.9]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskPlain:                                                        count:      4, total time:        15.1 ms [min:       1.1, max:       9.5, avg:       3.8]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskTypeByIdentifier:                                             count:   1500, total time:      4868.6 ms [min:       0.9, max:      38.1, avg:       3.2]
  com.evolveum.midpoint.wf.impl.hook.WfHook.invoke:                                                                                count:   2000, total time:        19.9 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.wf.impl.processors.primary.PrimaryChangeProcessor.previewOrProcessModelInvocation:                         count:    500, total time:         1.1 ms [min:       0.0, max:       0.0, avg:       0.0]
  interface com.evolveum.midpoint.notifications.api.NotificationManager.processEvent:                                              count:      1, total time:         6.0 ms [min:       6.0, max:       6.0, avg:       6.0]
  org.identityconnectors.framework.api.ConnectorFacade.getObject:                                                                  count:   6000, total time:      1644.8 ms [min:       0.1, max:      16.3, avg:       0.3]
  org.identityconnectors.framework.api.ConnectorFacade.search:                                                                     count:   5000, total time:     14296.1 ms [min:       0.8, max:      43.0, avg:       2.9]



