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

Iterative task information:
  Successfully processed:    500 in     556951 ms =   1113.9 ms per object, last: ShadowType:u-00000499 (u-00000499 (ACCOUNT - default - AccountObjectClass), bee26fc9-bace-4e1b-a8c0-9e7016bbfd27) on Tue May 04 04:52:57 CEST 2021 in 787 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:   1000 time(s), last: u-00000499 (Shadow u-00000499 (ACCOUNT - default - AccountObjectClass), bee26fc9-bace-4e1b-a8c0-9e7016bbfd27) on Tue May 04 04:52:57 CEST 2021
    ADD        UserType                       http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:    500 time(s), last: u-00000499 (User null (u-00000499), 588098bd-6b90-4134-a064-7ec4dc5d904c) on Tue May 04 04:52:57 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), 5996877f-75a7-4010-b3ca-3db28dd362a1) on Tue May 04 04:52:57 CEST 2021
    MODIFY     UserType                       http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:   3000 time(s), last: u-00000499 (User null (u-00000499), 588098bd-6b90-4134-a064-7ec4dc5d904c) on Tue May 04 04:52:57 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), 588098bd-6b90-4134-a064-7ec4dc5d904c) on Tue May 04 04:52:57 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), bee26fc9-bace-4e1b-a8c0-9e7016bbfd27) on Tue May 04 04:52:57 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), bc132148-30a7-4b5d-ad40-5e844a69f529) on Tue May 04 04:52:57 CEST 2021

Environmental performance information:
  Provisioning:
    source-000 (AccountObjectClass)                              count:        1, total time:      175 ms [min:      0, max:     16, avg:   175.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-003 (GroupObjectClass)                                count:     1942, total time:      198 ms [min:      0, max:      5, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:  1942:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-004 (GroupObjectClass)                                count:     1842, total time:      217 ms [min:      0, max:     20, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:  1842:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-004 (AccountObjectClass)                              count:      500, total time:      112 ms [min:      0, max:     15, 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:      116 ms [min:      0, max:     22, 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-001 (GroupObjectClass)                                count:     2193, total time:      189 ms [min:      0, max:      9, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:  2193:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-002 (AccountObjectClass)                              count:      500, total time:      145 ms [min:      0, max:     28, 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-001 (AccountObjectClass)                              count:      500, total time:       97 ms [min:      0, max:     18, 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-000 (GroupObjectClass)                                count:     1299, total time:       87 ms [min:      0, max:      4, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:  1299:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-000 (AccountObjectClass)                              count:      500, total time:       91 ms [min:      0, max:     10, 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-002 (GroupObjectClass)                                count:     2500, total time:      223 ms [min:      0, max:      6, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:  2500:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
  Mappings:
    RoleType (aggregated)                    count:  20000, total time:  10276 ms [min:     0, max:   109, avg:     0.5]
    target-004                               count:    500, total time:     84 ms [min:     0, max:    32, avg:     0.2]
    target-002                               count:    500, total time:     43 ms [min:     0, max:     1, avg:     0.1]
    source-000                               count: 103000, total time:  89215 ms [min:     0, max:   262, avg:     0.9]
    target-003                               count:    500, total time:     38 ms [min:     0, max:     1, avg:     0.1]
    target-001                               count:    500, total time:     39 ms [min:     0, max:     1, avg:     0.1]
    target-000                               count:    500, total time:     54 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:9cd6e5c5-97d5-4041-b4ae-13855ed3c702(source-000) in 1 ms.
  On:           2021-05-04T04:52:57.532+02:00

Repository performance information:
  addObject.ShadowType:                count:   3000, total time:    16663 ms [min:     2, max:    82, avg:     5.6], wasted time for    1 retry/retries:       38 ms [min:     0, max:    38, avg:     0.0] (  0.23%)
  addObject.UserType:                  count:    500, total time:     9877 ms [min:    13, max:    92, avg:    19.8], wasted time for    3 retry/retries:      146 ms [min:     0, max:    64, avg:     0.3] (  1.48%)
  getObject.ArchetypeType:             count:      2, total time:        2 ms [min:     1, max:     1, avg:     1.0]
  getObject.ObjectTemplateType:        count:      3, total time:       13 ms [min:     1, max:    11, avg:     4.3]
  getObject.RoleType:                  count:    156, total time:      191 ms [min:     0, max:    16, avg:     1.2]
  getObject.ShadowType:                count:   6500, total time:     5762 ms [min:     0, max:    29, avg:     0.9]
  getObject.SystemConfigurationType:   count:      1, total time:        3 ms [min:     3, max:     3, avg:     3.0]
  getObject.TaskType:                  count:      4, total time:       10 ms [min:     1, max:     4, avg:     2.5]
  getObject.UserType:                  count:   2504, total time:    28110 ms [min:     0, max:    78, avg:    11.2]
  getVersion.ResourceType:             count:     12, total time:        8 ms [min:     0, max:     1, avg:     0.7]
  getVersion.SystemConfigurationType:  count:      2, total time:        2 ms [min:     1, max:     1, avg:     1.0]
  modifyObject.ShadowType:             count:   4500, total time:    23891 ms [min:     2, max:   150, avg:     5.3], wasted time for   42 retry/retries:     1502 ms [min:     0, max:   146, avg:     0.3] (  6.29%)
  modifyObject.TaskType:               count:     35, total time:      885 ms [min:     7, max:    56, avg:    25.3]
  modifyObject.UserType:               count:   3000, total time:   144865 ms [min:    31, max:   537, avg:    48.3], wasted time for   44 retry/retries:     3651 ms [min:     0, max:   434, avg:     1.2] (  2.52%)
  searchObjects.FunctionLibraryType:   count:      1, total time:       12 ms [min:    12, max:    12, avg:    12.0]
  searchObjects.RoleType:              count:    202, total time:      642 ms [min:     0, max:    39, avg:     3.2]
  searchObjects.ShadowType:            count:    816, total time:     1519 ms [min:     0, max:    41, avg:     1.9]
  searchObjects.TaskType:              count:   1500, total time:     6839 ms [min:     1, max:    46, avg:     4.6]
  searchObjects.UserType:              count:    500, total time:      349 ms [min:     0, max:    16, avg:     0.7]
  searchShadowOwner.FocusType:         count:    500, total time:      410 ms [min:     0, max:    17, avg:     0.8]

Cache performance information:
  com.evolveum.midpoint.provisioning.impl.ResourceCache:                               hits:  24504 (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:    497 ( 99.4%), weak hits:      0 (0.0%), misses:      3, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.RoleType:                  hits:  10120 ( 98.5%), weak hits:      0 (0.0%), misses:    156, 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:   4798 ( 96.0%), weak hits:      0 (0.0%), misses:    202, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalQueryCache.ShadowType:                 hits:   9460 ( 92.1%), weak hits:      0 (0.0%), misses:    316, 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:     84 ( 97.7%), 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:  25724 ( 71.5%), weak hits:      0 (0.0%), misses:  10276, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ShadowType:                  hits:  13276 ( 67.1%), 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:   5000 ( 50.0%), weak hits:      0 (0.0%), misses:   5000, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalQueryCache.ShadowType:                   hits:    224 (  2.1%), weak hits:      0 (0.0%), misses:   9776, 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:  21500 ( 87.7%), weak hits:      0 (0.0%), misses:   3000, passes:      0, not available:      4
  com.evolveum.midpoint.repo.cache.local.LocalVersionCache.SystemConfigurationType:    hits:      3 (  3.4%), weak hits:      0 (0.0%), misses:     86, passes:      0, not available:      0

Methods performance information:
  class com.evolveum.midpoint.model.common.stringpolicy.ObjectValuePolicyEvaluator.validateValue:                                  count:   1500, total time:         1.2 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: 252500, total time:     59242.3 ms [min:       0.0, max:     240.0, avg:       0.2]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.SingleShotEvaluation.evaluateExpression:                  count: 150500, total time:      2234.9 ms [min:       0.0, max:     108.4, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.ValueTupleTransformation.evaluate:                        count: 311000, total time:     31956.8 ms [min:       0.0, max:     233.4, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpression.evaluate:                                                  count: 450500, total time:      4903.8 ms [min:       0.0, max:     232.7, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpressionFactory.searchCustomFunctions:                              count:      1, total time:        14.2 ms [min:      14.2, max:      14.2, avg:      14.2]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluate:                                                                 count: 125500, total time:     98603.7 ms [min:       0.0, max:     261.5, avg:       0.8]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluatePrepared:                                                         count: 125500, total time:     71515.7 ms [min:       0.0, max:     260.2, avg:       0.6]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.prepare:                                                                  count: 125500, total time:     25804.3 ms [min:       0.0, max:      34.0, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute:                                                                    count:   1000, total time:    214283.8 ms [min:       0.1, max:    1006.8, avg:     214.3]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.focus.UserType:                                                     count:   1000, total time:     14280.9 ms [min:       0.1, max:     100.3, avg:      14.3]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.projection.ShadowType:                                              count:   3000, total time:     25580.1 ms [min:       4.0, max:      91.5, avg:       8.5]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.executeDelta:                                                               count:   3500, total time:     35052.8 ms [min:       3.9, max:      92.6, avg:      10.0]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.linkShadow:                                                                 count:   3000, total time:    145621.6 ms [min:      31.3, max:     536.9, avg:      48.5]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.updateSituationInShadow:                                                    count:   3000, total time:     28247.7 ms [min:       5.9, max:     124.9, avg:       9.4]
  com.evolveum.midpoint.model.impl.lens.Clockwork.authorizeRequest:                                                                count:    500, total time:        12.8 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.Clockwork.click:                                                                           count:   2500, total time:    551314.2 ms [min:       3.6, max:    1497.0, avg:     220.5]
  com.evolveum.midpoint.model.impl.lens.Clockwork.execution:                                                                       count:   1000, total time:    214290.9 ms [min:       0.1, max:    1006.8, avg:     214.3]
  com.evolveum.midpoint.model.impl.lens.Clockwork.run:                                                                             count:    500, total time:    551451.6 ms [min:     748.9, max:    2760.1, avg:    1102.9]
  com.evolveum.midpoint.model.impl.lens.IvwoConsolidator.consolidateToDelta:                                                       count: 119836, total time:      1774.1 ms [min:       0.0, max:      28.8, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.assignments.AssignmentEvaluator.evaluate:                                                  count:  11000, total time:      5979.1 ms [min:       0.2, max:      26.9, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.assignments.PathSegmentEvaluation.evaluate:                                                count:  77000, total time:     11250.3 ms [min:       0.0, max:      26.9, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.AssignedResourceObjectConstruction.evaluate:                                  count:  25000, total time:     16793.4 ms [min:       0.0, max:     132.3, avg:       0.7]
  com.evolveum.midpoint.model.impl.lens.construction.EvaluatedResourceObjectConstructionImpl.evaluate:                             count:  28000, total time:     16870.0 ms [min:       0.0, max:     131.8, avg:       0.6]
  com.evolveum.midpoint.model.impl.lens.construction.PlainResourceObjectConstruction.evaluate:                                     count:   3000, total time:       702.2 ms [min:       0.0, max:      32.2, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.ActivationProcessor.projectionActivation:                                        count:   6000, total time:       273.6 ms [min:       0.0, max:      16.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateItem:                                          count:  14836, total time:       602.5 ms [min:       0.0, max:      27.2, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateValues:                                        count:   3000, total time:      5801.1 ms [min:       0.5, max:      48.7, avg:       1.9]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.determineFocusContext:                                             count:   3500, total time:     28542.4 ms [min:       0.0, max:      79.2, avg:       8.2]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.load:                                                              count:   1000, total time:      7037.8 ms [min:       0.1, max:      61.6, avg:       7.0]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.loadProjection:                                                    count:   3500, total time:      6948.0 ms [min:       0.0, max:      49.7, avg:       2.0]
  com.evolveum.midpoint.model.impl.lens.projector.DependencyProcessor.sortProjectionsToWaves:                                      count:   1000, total time:         8.0 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ProjectionValuesProcessor.iteration:                                             count:   3000, total time:     16200.3 ms [min:       2.0, max:      57.8, avg:       5.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.activation:                                                            count:   1000, total time:       395.5 ms [min:       0.0, max:      16.7, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignments:                                                           count:   1000, total time:     32742.0 ms [min:       7.4, max:     371.6, avg:      32.7]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsConflicts:                                                  count:   1000, total time:        12.6 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsMembershipAndDelegate:                                      count:   1000, total time:      1392.4 ms [min:       0.2, max:      22.0, avg:       1.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsOrg:                                                        count:   1000, total time:        10.3 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focus:                                                                 count:   1000, total time:    216892.7 ms [min:      60.7, max:    1405.3, avg:     216.9]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusActivation:                                                       count:   3000, total time:       828.9 ms [min:       0.0, max:      23.9, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusCredentials:                                                      count:   1000, total time:        40.1 ms [min:       0.0, max:      11.4, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusLifecycle:                                                        count:   1000, total time:         2.9 ms [min:       0.0, max:       0.4, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusPolicyRules:                                                      count:   1000, total time:        22.1 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.inbound:                                                               count:   1000, total time:    176369.1 ms [min:      51.8, max:    1033.6, avg:     176.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.load:                                                                  count:   1000, total time:      7061.0 ms [min:       0.1, max:      61.7, avg:       7.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterAssignments:                                        count:   1000, total time:      1319.0 ms [min:       0.2, max:      35.1, avg:       1.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterProjections:                                        count:   1000, total time:      1364.0 ms [min:       0.1, max:      22.8, avg:       1.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateBeforeAssignments:                                       count:   1000, total time:      1161.2 ms [min:       0.0, max:      30.2, avg:       1.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.project:                                                               count:   1000, total time:    245241.5 ms [min:      69.9, max:    1496.8, avg:     245.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectProjection:                                                     count:   3000, total time:     19411.8 ms [min:       2.5, max:      58.7, avg:       6.5]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projection:                                                            count:   6000, total time:     19440.9 ms [min:       0.0, max:      58.7, avg:       3.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionCredentials:                                                 count:   3000, total time:       525.1 ms [min:       0.0, max:      27.9, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionLifecycle:                                                   count:   3000, total time:       624.5 ms [min:       0.0, max:      24.3, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionReconciliation:                                              count:   3000, total time:       557.8 ms [min:       0.0, max:      16.5, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValues:                                                      count:   3000, total time:     16818.5 ms [min:       2.1, max:      58.0, avg:       5.6]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValuesPostRecon:                                             count:   3000, total time:       692.7 ms [min:       0.0, max:      23.3, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.distributeConstructions:                               count:   1000, total time:        84.5 ms [min:       0.0, max:      25.2, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.evaluateFocusMappings:                                 count:   1000, total time:       856.0 ms [min:       0.0, max:      18.7, avg:       0.9]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processAssignments:                                    count:   1000, total time:     32734.7 ms [min:       7.4, max:     371.6, avg:      32.7]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processProjections:                                    count:   1000, total time:     17695.4 ms [min:       0.6, max:     307.3, avg:      17.7]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentTripleEvaluator.evaluateAssignment:                              count:  11000, total time:      6193.0 ms [min:       0.2, max:      26.9, avg:       0.6]
  com.evolveum.midpoint.model.impl.lens.projector.focus.PruningOperation.execute:                                                  count:   1000, total time:         7.6 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.consolidation.DeltaSetTripleMapConsolidation.consolidate:                  count:   1000, total time:      2674.5 ms [min:       1.4, max:      41.4, avg:       2.7]
  com.evolveum.midpoint.model.impl.lens.projector.policy.PolicyRuleEnforcer.execute:                                               count:    500, total time:         6.7 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.notifyChange:                                                   count:   1000, total time:    558750.9 ms [min:       3.1, max:    2816.7, avg:     558.8]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.setupSituation:                                                 count:    500, total time:      1040.0 ms [min:       1.0, max:      29.7, avg:       2.1]
  com.evolveum.midpoint.model.impl.sync.SynchronizeAccountResultHandler.handle:                                                    count:    500, total time:    556951.5 ms [min:     756.1, max:    2819.0, avg:    1113.9]
  com.evolveum.midpoint.model.impl.util.AuditHelper.audit:                                                                         count:   1000, total time:     58222.6 ms [min:       1.5, max:     240.0, avg:      58.2]
  com.evolveum.midpoint.model.impl.util.AuditHelper.resolveName:                                                                   count:  20776, total time:       409.3 ms [min:       0.0, max:      24.9, 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:     19788.8 ms [min:       3.7, max:      90.8, avg:       7.9]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.countObjects:                                                         count:      1, total time:         0.6 ms [min:       0.6, max:       0.6, avg:       0.6]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.getObject:                                                            count:   8502, total time:     16392.3 ms [min:       0.0, max:      49.7, avg:       1.9]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.modifyObject:                                                         count:   3500, total time:     22468.4 ms [min:       3.9, max:     122.7, avg:       6.4]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjects:                                                        count:  10000, total time:      4601.6 ms [min:       0.2, max:      58.6, avg:       0.5]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjectsIterative:                                               count:      1, total time:     90810.9 ms [min:   90810.9, max:   90810.9, avg:   90810.9]
  com.evolveum.midpoint.provisioning.impl.AccessChecker.accessCheck:                                                               count:   6500, total time:       135.8 ms [min:       0.0, max:       1.1, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ProvisioningContext.getConnectorInstance:                                                count:   9960, total time:       170.3 ms [min:       0.0, max:      15.3, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ResourceObjectConverter.addResourceObject:                                               count:   2500, total time:      3768.5 ms [min:       0.1, max:      40.9, avg:       1.5]
  com.evolveum.midpoint.provisioning.impl.ResourceObjectConverter.modifyEntitlement:                                               count:   9776, total time:      2041.6 ms [min:       0.1, max:      38.6, avg:       0.2]
  com.evolveum.midpoint.provisioning.impl.ShadowCache.handleObject:                                                                count:  10000, total time:       280.7 ms [min:       0.0, max:      19.2, avg:       0.0]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.addObject:                                                          count:   2500, total time:       797.5 ms [min:       0.1, max:      28.5, avg:       0.3]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.modifyObject:                                                       count:   9776, total time:      1619.5 ms [min:       0.0, max:      36.8, avg:       0.2]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.search:                                                             count:      1, total time:     90806.8 ms [min:   90806.8, max:   90806.8, avg:   90806.8]
  com.evolveum.midpoint.provisioning.ucf.impl.connid.ConnIdConvertor.convertToResourceObject:                                      count:    500, total time:       289.7 ms [min:       0.2, max:      25.1, avg:       0.6]
  com.evolveum.midpoint.repo.api.RepositoryService.addObject:                                                                      count:   3500, total time:     26330.0 ms [min:       2.6, max:      91.6, avg:       7.5]
  com.evolveum.midpoint.repo.api.RepositoryService.getObject:                                                                      count:   9170, total time:     34069.5 ms [min:       0.4, max:      78.0, avg:       3.7]
  com.evolveum.midpoint.repo.api.RepositoryService.getVersion:                                                                     count:     14, total time:         7.0 ms [min:       0.4, max:       0.6, avg:       0.5]
  com.evolveum.midpoint.repo.api.RepositoryService.modifyObject:                                                                   count:   7535, total time:    164311.1 ms [min:       0.9, max:     165.2, avg:      21.8]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjects:                                                                  count:   3019, total time:      9347.4 ms [min:       0.3, max:      45.7, avg:       3.1]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjectsIterative:                                                         count:      1, total time:        13.9 ms [min:      13.9, max:      13.9, avg:      13.9]
  com.evolveum.midpoint.repo.api.RepositoryService.searchShadowOwner:                                                              count:    500, total time:       417.2 ms [min:       0.3, max:      16.8, avg:       0.8]
  com.evolveum.midpoint.repo.cache.RepositoryCache.addObject:                                                                      count:   3500, total time:     27610.6 ms [min:       2.8, max:      92.1, avg:       7.9]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getObject:                                                                      count:  60777, total time:     46171.4 ms [min:       0.0, max:      78.0, avg:       0.8]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getVersion:                                                                     count:  24593, total time:       154.9 ms [min:       0.0, max:       2.0, avg:       0.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.invalidateCacheEntries:                                                         count:  11000, total time:      2652.1 ms [min:       0.0, max:      36.7, avg:       0.2]
  com.evolveum.midpoint.repo.cache.RepositoryCache.modifyObject:                                                                   count:   7500, total time:    170762.0 ms [min:       3.1, max:     536.9, avg:      22.8]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchObjects:                                                                  count:  21000, total time:      5477.8 ms [min:       0.0, max:      58.3, avg:       0.3]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchShadowOwner:                                                              count:    500, total time:       425.9 ms [min:       0.3, max:      16.8, avg:       0.9]
  com.evolveum.midpoint.schema.result.searchResult:                                                                                count:    500, total time:     90305.0 ms [min:       9.6, max:    2236.8, avg:     180.6]
  com.evolveum.midpoint.task.api.Task.getPathToRootTask:                                                                           count:   1500, total time:      8204.7 ms [min:       2.6, max:      47.8, avg:       5.5]
  com.evolveum.midpoint.task.api.TaskManager.createTaskInstance:                                                                   count:   1504, total time:      1096.8 ms [min:       0.4, max:      34.5, avg:       0.7]
  com.evolveum.midpoint.task.api.TaskManager.getTaskByIdentifier:                                                                  count:   1500, total time:      8192.1 ms [min:       2.5, max:      47.8, avg:       5.5]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskPlain:                                                        count:      4, total time:        15.0 ms [min:       2.7, max:       4.9, avg:       3.7]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskTypeByIdentifier:                                             count:   1500, total time:      7059.8 ms [min:       1.6, max:      45.9, avg:       4.7]
  com.evolveum.midpoint.wf.impl.hook.WfHook.invoke:                                                                                count:   2000, total time:        48.3 ms [min:       0.0, max:       4.7, avg:       0.0]
  com.evolveum.midpoint.wf.impl.processors.primary.PrimaryChangeProcessor.previewOrProcessModelInvocation:                         count:    500, total time:         6.5 ms [min:       0.0, max:       0.4, avg:       0.0]
  interface com.evolveum.midpoint.notifications.api.NotificationManager.processEvent:                                              count:      1, total time:         5.9 ms [min:       5.9, max:       5.9, avg:       5.9]
  org.identityconnectors.framework.api.ConnectorFacade.create:                                                                     count:   2500, total time:       679.1 ms [min:       0.1, max:      28.4, avg:       0.3]
  org.identityconnectors.framework.api.ConnectorFacade.search:                                                                     count:      1, total time:     90804.3 ms [min:   90804.3, max:   90804.3, avg:   90804.3]
  org.identityconnectors.framework.api.ConnectorFacade.updateDelta:                                                                count:   9776, total time:      1054.5 ms [min:       0.0, max:      20.4, avg:       0.1]



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

Iterative task information:
  Successfully processed:    500 in     211956 ms =    423.9 ms per object, last: ShadowType:u-00000499 (u-00000499 (ACCOUNT - default - AccountObjectClass), bee26fc9-bace-4e1b-a8c0-9e7016bbfd27) on Tue May 04 04:53:34 CEST 2021 in 361 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), bee26fc9-bace-4e1b-a8c0-9e7016bbfd27) on Tue May 04 04:53:33 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), bee26fc9-bace-4e1b-a8c0-9e7016bbfd27) on Tue May 04 04:53:33 CEST 2021

Environmental performance information:
  Provisioning:
    source-000 (AccountObjectClass)                              count:        1, total time:      124 ms [min:      0, max:     13, avg:   124.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:  20000, total time:   8756 ms [min:     0, max:    25, avg:     0.4]
    target-004                               count:    500, total time:     55 ms [min:     0, max:     1, avg:     0.1]
    target-002                               count:    500, total time:     57 ms [min:     0, max:     1, avg:     0.1]
    source-000                               count: 103000, total time:  78980 ms [min:     0, max:    32, avg:     0.8]
    target-003                               count:    500, total time:     55 ms [min:     0, max:     1, avg:     0.1]
    target-001                               count:    500, total time:     56 ms [min:     0, max:     1, avg:     0.1]
    target-000                               count:    500, total time:     66 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:9cd6e5c5-97d5-4041-b4ae-13855ed3c702(source-000) in 0 ms.
  On:           2021-05-04T04:53:34.177+02:00

Repository performance information:
  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:    155, total time:      135 ms [min:     0, max:     2, avg:     0.9]
  getObject.ShadowType:          count:   1835, total time:      948 ms [min:     0, max:    13, avg:     0.5]
  getObject.TaskType:            count:      4, total time:        9 ms [min:     1, max:     4, avg:     2.3]
  getObject.UserType:            count:   2004, total time:    13050 ms [min:     0, max:    47, avg:     6.5]
  modifyObject.ShadowType:       count:    500, total time:     2251 ms [min:     3, max:    21, avg:     4.5]
  modifyObject.TaskType:         count:     18, total time:      290 ms [min:     5, max:    21, avg:    16.1]
  searchObjects.RoleType:        count:     89, total time:       70 ms [min:     0, max:     2, avg:     0.8]
  searchObjects.ShadowType:      count:    641, total time:      679 ms [min:     0, max:    16, avg:     1.1]
  searchObjects.TaskType:        count:   1500, total time:     3896 ms [min:     0, max:    27, avg:     2.6]
  searchShadowOwner.FocusType:   count:    500, total time:    12435 ms [min:    18, max:    61, avg:    24.9]

Cache performance information:
  com.evolveum.midpoint.provisioning.impl.ResourceCache:                               hits:  18004 (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:  10121 ( 98.5%), weak hits:      0 (0.0%), misses:    155, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ShadowType:                hits:   1165 ( 38.8%), weak hits:      0 (0.0%), misses:   1835, 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:   4911 ( 98.2%), weak hits:      0 (0.0%), misses:     89, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalQueryCache.ShadowType:                 hits:   9635 ( 93.8%), weak hits:      0 (0.0%), misses:    141, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalVersionCache.ResourceType:             hits:   3004 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalVersionCache.SystemConfigurationType:  hits:     35 (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:  20724 ( 66.9%), weak hits:      0 (0.0%), misses:  10276, 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:   5000 ( 50.0%), weak hits:      0 (0.0%), misses:   5000, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalQueryCache.ShadowType:                   hits:    224 (  2.1%), weak hits:      0 (0.0%), misses:   9776, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalVersionCache.ResourceType:               hits:  15000 ( 83.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:     35, 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: 383000, total time:     53321.1 ms [min:       0.0, max:      31.7, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.SingleShotEvaluation.evaluateExpression:                  count: 291000, total time:      2768.0 ms [min:       0.0, max:      16.9, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.ValueTupleTransformation.evaluate:                        count: 311000, total time:     26114.2 ms [min:       0.0, max:      25.4, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpression.evaluate:                                                  count: 591000, total time:      3009.0 ms [min:       0.0, max:      16.9, avg:       0.0]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluate:                                                                 count: 125500, total time:     87265.3 ms [min:       0.0, max:      32.2, avg:       0.7]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluatePrepared:                                                         count: 125500, total time:     62799.7 ms [min:       0.0, max:      32.2, avg:       0.5]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.prepare:                                                                  count: 125500, total time:     23453.3 ms [min:       0.0, max:      22.0, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute:                                                                    count:   1000, total time:      1234.1 ms [min:       0.0, max:       5.7, avg:       1.2]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.projection.ShadowType:                                              count:   3000, total time:      1207.6 ms [min:       0.0, max:       5.1, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.updateSituationInShadow:                                                    count:   3000, total time:      1179.6 ms [min:       0.0, max:       3.1, 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:    189536.0 ms [min:       0.1, max:     342.3, avg:      75.8]
  com.evolveum.midpoint.model.impl.lens.Clockwork.execution:                                                                       count:   1000, total time:      1238.3 ms [min:       0.0, max:       5.7, avg:       1.2]
  com.evolveum.midpoint.model.impl.lens.Clockwork.run:                                                                             count:    500, total time:    189627.8 ms [min:     286.3, max:     442.0, avg:     379.3]
  com.evolveum.midpoint.model.impl.lens.IvwoConsolidator.consolidateToDelta:                                                       count: 112418, total time:      1174.0 ms [min:       0.0, max:      14.2, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.assignments.AssignmentEvaluator.evaluate:                                                  count:  11000, total time:      5095.6 ms [min:       0.1, max:      20.1, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.assignments.PathSegmentEvaluation.evaluate:                                                count:  77000, total time:      9651.7 ms [min:       0.0, max:      20.0, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.AssignedResourceObjectConstruction.evaluate:                                  count:  25000, total time:     14340.6 ms [min:       0.0, max:      26.2, avg:       0.6]
  com.evolveum.midpoint.model.impl.lens.construction.EvaluatedResourceObjectConstructionImpl.evaluate:                             count:  28000, total time:     14812.8 ms [min:       0.0, max:      26.2, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.construction.PlainResourceObjectConstruction.evaluate:                                     count:   3000, total time:       628.7 ms [min:       0.0, max:       1.3, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.ActivationProcessor.projectionActivation:                                        count:   6000, total time:        23.5 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateItem:                                          count:   7418, total time:       101.5 ms [min:       0.0, max:       0.4, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateValues:                                        count:   3000, total time:       444.2 ms [min:       0.0, max:      16.5, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.determineFocusContext:                                             count:   3000, total time:     12310.5 ms [min:       0.0, max:      46.6, avg:       4.1]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.load:                                                              count:    500, total time:      3935.2 ms [min:       1.2, max:      27.3, avg:       7.9]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.loadProjection:                                                    count:   3000, total time:       206.5 ms [min:       0.0, max:       0.2, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.DependencyProcessor.sortProjectionsToWaves:                                      count:   1000, total time:         1.8 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ProjectionValuesProcessor.iteration:                                             count:   3000, total time:      1137.3 ms [min:       0.0, max:      16.8, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.activation:                                                            count:   1000, total time:       241.5 ms [min:       0.0, max:       0.9, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignments:                                                           count:   1000, total time:     23278.5 ms [min:       5.3, max:      66.1, avg:      23.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:        51.9 ms [min:       0.0, max:       0.6, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsOrg:                                                        count:   1000, total time:         8.0 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focus:                                                                 count:   1000, total time:    168362.4 ms [min:      57.9, max:     297.4, avg:     168.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusActivation:                                                       count:   3000, total time:        73.1 ms [min:       0.0, max:       0.2, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusCredentials:                                                      count:   1000, total time:         5.6 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:        13.2 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.inbound:                                                               count:   1000, total time:    144789.6 ms [min:      51.8, max:     250.3, avg:     144.8]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.load:                                                                  count:    500, total time:      3938.3 ms [min:       1.2, max:      27.3, avg:       7.9]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterAssignments:                                        count:   1000, total time:        18.9 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterProjections:                                        count:   1000, total time:        13.8 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateBeforeAssignments:                                       count:   1000, total time:        20.2 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.project:                                                               count:   1000, total time:    173978.8 ms [min:      57.9, max:     315.3, avg:     174.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectProjection:                                                     count:   3000, total time:      1355.6 ms [min:       0.2, max:      16.9, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projection:                                                            count:   6000, total time:      1372.3 ms [min:       0.0, max:      16.9, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionCredentials:                                                 count:   3000, total time:        27.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionLifecycle:                                                   count:   3000, total time:         6.3 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionReconciliation:                                              count:   3000, total time:        86.2 ms [min:       0.0, max:       0.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValues:                                                      count:   3000, total time:      1165.7 ms [min:       0.0, max:      16.9, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValuesPostRecon:                                             count:   3000, total time:         3.2 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.distributeConstructions:                               count:   1000, total time:        46.4 ms [min:       0.0, max:       0.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.evaluateFocusMappings:                                 count:   1000, total time:        11.1 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processAssignments:                                    count:   1000, total time:     23273.1 ms [min:       5.3, max:      66.1, avg:      23.3]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processProjections:                                    count:   1000, total time:     14474.2 ms [min:       0.1, max:      55.1, avg:      14.5]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentTripleEvaluator.evaluateAssignment:                              count:  11000, total time:      5252.6 ms [min:       0.2, max:      20.1, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.projector.focus.PruningOperation.execute:                                                  count:   1000, total time:         2.2 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:      2428.7 ms [min:       1.6, max:      15.7, avg:       2.4]
  com.evolveum.midpoint.model.impl.lens.projector.policy.PolicyRuleEnforcer.execute:                                               count:    500, total time:         4.8 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.notifyChange:                                                   count:    500, total time:    211365.4 ms [min:     325.2, max:     488.1, avg:     422.7]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.setupSituation:                                                 count:    500, total time:     18993.9 ms [min:      28.4, max:      75.0, avg:      38.0]
  com.evolveum.midpoint.model.impl.sync.SynchronizeAccountResultHandler.handle:                                                    count:    500, total time:    211946.2 ms [min:     338.3, max:     489.2, avg:     423.9]
  com.evolveum.midpoint.notifications.impl.NotificationHook.invoke:                                                                count:   2000, total time:         2.0 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:      4428.2 ms [min:       0.0, max:      15.2, avg:       0.6]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjects:                                                        count:  10000, total time:      2579.1 ms [min:       0.2, max:      17.3, avg:       0.3]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjectsIterative:                                               count:      1, total time:     34338.5 ms [min:   34338.5, max:   34338.5, avg:   34338.5]
  com.evolveum.midpoint.provisioning.impl.AccessChecker.accessCheck:                                                               count:    500, total time:       104.5 ms [min:       0.1, max:       3.7, avg:       0.2]
  com.evolveum.midpoint.provisioning.impl.ProvisioningContext.getConnectorInstance:                                                count:      1, total time:         0.1 ms [min:       0.1, max:       0.1, avg:       0.1]
  com.evolveum.midpoint.provisioning.impl.ShadowCache.handleObject:                                                                count:  10000, total time:       188.3 ms [min:       0.0, max:       2.5, avg:       0.0]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.search:                                                             count:      1, total time:     34338.2 ms [min:   34338.2, max:   34338.2, avg:   34338.2]
  com.evolveum.midpoint.provisioning.ucf.impl.connid.ConnIdConvertor.convertToResourceObject:                                      count:    500, total time:       208.2 ms [min:       0.2, max:       1.1, avg:       0.4]
  com.evolveum.midpoint.repo.api.RepositoryService.getObject:                                                                      count:   4000, total time:     14188.0 ms [min:       0.3, max:      46.5, avg:       3.5]
  com.evolveum.midpoint.repo.api.RepositoryService.modifyObject:                                                                   count:    518, total time:      2512.8 ms [min:       3.5, max:      20.8, avg:       4.9]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjects:                                                                  count:   2230, total time:      4654.3 ms [min:       0.5, max:      26.8, avg:       2.1]
  com.evolveum.midpoint.repo.api.RepositoryService.searchShadowOwner:                                                              count:    500, total time:     12422.8 ms [min:      18.5, max:      60.4, avg:      24.8]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getObject:                                                                      count:  41001, total time:     17979.8 ms [min:       0.0, max:      46.6, avg:       0.4]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getVersion:                                                                     count:  18039, total time:        66.3 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.invalidateCacheEntries:                                                         count:    500, total time:       111.5 ms [min:       0.1, max:       0.6, avg:       0.2]
  com.evolveum.midpoint.repo.cache.RepositoryCache.modifyObject:                                                                   count:    500, total time:      2348.9 ms [min:       3.8, max:      20.7, avg:       4.7]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchObjects:                                                                  count:  20500, total time:      2690.7 ms [min:       0.0, max:      17.2, avg:       0.1]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchShadowOwner:                                                              count:    500, total time:     18985.8 ms [min:      28.4, max:      75.0, avg:      38.0]
  com.evolveum.midpoint.schema.result.searchResult:                                                                                count:    500, total time:     33976.7 ms [min:       1.7, max:     375.7, avg:      68.0]
  com.evolveum.midpoint.task.api.Task.getPathToRootTask:                                                                           count:   1500, total time:      4938.1 ms [min:       1.2, max:      27.5, avg:       3.3]
  com.evolveum.midpoint.task.api.TaskManager.createTaskInstance:                                                                   count:   1504, total time:       816.9 ms [min:       0.4, max:      15.2, avg:       0.5]
  com.evolveum.midpoint.task.api.TaskManager.getTaskByIdentifier:                                                                  count:   1500, total time:      4928.9 ms [min:       1.2, max:      27.5, avg:       3.3]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskPlain:                                                        count:      4, total time:        10.3 ms [min:       1.5, max:       4.5, avg:       2.6]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskTypeByIdentifier:                                             count:   1500, total time:      4101.6 ms [min:       0.8, max:      26.9, avg:       2.7]
  com.evolveum.midpoint.wf.impl.hook.WfHook.invoke:                                                                                count:   2000, total time:        26.0 ms [min:       0.0, max:       0.3, 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:     34337.6 ms [min:   34337.6, max:   34337.6, avg:   34337.6]



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

Iterative task information:
  Successfully processed:    500 in     185598 ms =    371.2 ms per object, last: null:u-00000274 (User null (u-00000274), ffa7e553-d1da-4ede-960a-b1b444fd65ba) on Tue May 04 04:54:09 CEST 2021 in 278 ms
  Failed:                      0 in          0 ms =      0.0 ms per object

Actions executed:
  All object actions:
  Resulting object actions:

Environmental performance information:
  Provisioning:
    target-004 (GroupObjectClass)                                count:     1000, total time:     1828 ms [min:      0, max:     33, 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-000 (AccountObjectClass)                              count:     1000, total time:      110 ms [min:      0, max:      1, avg:     0.1] 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-003 (GroupObjectClass)                                count:     1000, total time:     1881 ms [min:      0, max:     39, avg:     1.9] GET:     0:  0 SEARCH:  1000:  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:      676 ms [min:      0, max:     16, 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-000 (GroupObjectClass)                                count:     1000, total time:     1745 ms [min:      0, max:     39, 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-001 (GroupObjectClass)                                count:     1000, total time:     1872 ms [min:      0, max:     25, avg:     1.9] 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:      155 ms [min:      0, max:      8, 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-001 (AccountObjectClass)                              count:     1000, total time:      134 ms [min:      0, max:      9, avg:     0.1] 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-002 (GroupObjectClass)                                count:     1000, total time:     1816 ms [min:      0, max:     38, 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-003 (AccountObjectClass)                              count:     1000, total time:      171 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-004 (AccountObjectClass)                              count:     1000, total time:      215 ms [min:      0, max:     26, 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:  20000, total time:   8994 ms [min:     0, max:    30, avg:     0.4]
    target-004                               count:    500, total time:     58 ms [min:     0, max:     1, avg:     0.1]
    target-002                               count:    500, total time:     65 ms [min:     0, max:     1, avg:     0.1]
    source-000                               count: 103000, total time:  56971 ms [min:     0, max:    45, avg:     0.6]
    target-003                               count:    500, total time:     62 ms [min:     0, max:     1, avg:     0.1]
    target-001                               count:    500, total time:     53 ms [min:     0, max:     1, avg:     0.1]
    target-000                               count:    500, total time:     63 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:9cd6e5c5-97d5-4041-b4ae-13855ed3c702(source-000) in 1 ms.
  On:           2021-05-04T04:54:09.353+02:00

Repository performance information:
  countObjects.UserType:               count:      1, total time:       10 ms [min:    10, max:    10, avg:    10.0]
  getObject.RoleType:                  count:     62, total time:       63 ms [min:     0, max:    13, avg:     1.0]
  getObject.ShadowType:                count:   1128, total time:      680 ms [min:     0, max:    10, avg:     0.6]
  getObject.SystemConfigurationType:   count:      1, total time:        3 ms [min:     3, max:     3, avg:     3.0]
  getObject.TaskType:                  count:      4, total time:       12 ms [min:     1, max:     7, avg:     3.0]
  getObject.UserType:                  count:   2004, total time:    13723 ms [min:     0, max:    70, avg:     6.8]
  getVersion.ResourceType:             count:      7, total time:        3 ms [min:     0, max:     1, avg:     0.4]
  getVersion.SystemConfigurationType:  count:      1, total time:        1 ms [min:     1, max:     1, avg:     1.0]
  modifyObject.TaskType:               count:     17, total time:      259 ms [min:     5, max:    23, avg:    15.2]
  searchObjects.RoleType:              count:     11, total time:       10 ms [min:     0, max:     1, avg:     0.9]
  searchObjects.ShadowType:            count:  19564, total time:    15174 ms [min:     0, max:    30, avg:     0.8]
  searchObjects.TaskType:              count:   1500, total time:     4554 ms [min:     0, max:    26, avg:     3.0]
  searchObjects.UserType:              count:     11, total time:    11671 ms [min:     2, max:  1349, avg:  1061.0]

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:  34000 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ArchetypeType:             hits:    500 (100.0%), weak hits:      0 (0.0%), misses:      0, 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:  10214 ( 99.4%), weak hits:      0 (0.0%), misses:     62, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ShadowType:                hits:   1872 ( 62.4%), weak hits:      0 (0.0%), misses:   1128, 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:   4989 ( 99.8%), weak hits:      0 (0.0%), misses:     11, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalQueryCache.ShadowType:                 hits:   9764 ( 33.3%), weak hits:      0 (0.0%), misses:     12, passes:  19552, 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:   2993 ( 99.8%), weak hits:      0 (0.0%), misses:      7, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalVersionCache.SystemConfigurationType:  hits:     32 ( 97.0%), 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:  20724 ( 66.9%), weak hits:      0 (0.0%), misses:  10276, 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:   5000 ( 50.0%), weak hits:      0 (0.0%), misses:   5000, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalQueryCache.ShadowType:                   hits:    224 (  0.8%), weak hits:      0 (0.0%), misses:   9776, passes:  19552, 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:  31000 ( 91.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:     33, 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: 383000, total time:     54313.6 ms [min:       0.0, max:      44.9, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.SingleShotEvaluation.evaluateExpression:                  count: 291000, total time:      2827.8 ms [min:       0.0, max:      25.4, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.ValueTupleTransformation.evaluate:                        count: 311000, total time:     27589.7 ms [min:       0.0, max:      29.2, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpression.evaluate:                                                  count: 591000, total time:      3107.5 ms [min:       0.0, max:      26.0, avg:       0.0]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluate:                                                                 count: 125500, total time:     65356.5 ms [min:       0.0, max:      45.0, avg:       0.5]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluatePrepared:                                                         count: 125500, total time:     63767.8 ms [min:       0.0, max:      45.0, avg:       0.5]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.prepare:                                                                  count: 125500, total time:       595.1 ms [min:       0.0, max:       0.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute:                                                                    count:   1000, total time:       315.5 ms [min:       0.0, max:      16.1, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.projection.ShadowType:                                              count:   3000, total time:       292.1 ms [min:       0.0, max:      15.5, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.updateSituationInShadow:                                                    count:   3000, total time:       266.8 ms [min:       0.0, max:      15.5, 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:    185332.9 ms [min:       0.1, max:     539.0, avg:      74.1]
  com.evolveum.midpoint.model.impl.lens.Clockwork.execution:                                                                       count:   1000, total time:       319.4 ms [min:       0.0, max:      16.1, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.Clockwork.run:                                                                             count:    500, total time:    185416.0 ms [min:     258.4, max:     653.4, avg:     370.8]
  com.evolveum.midpoint.model.impl.lens.IvwoConsolidator.consolidateToDelta:                                                       count: 119836, total time:      1385.4 ms [min:       0.0, max:      25.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.assignments.AssignmentEvaluator.evaluate:                                                  count:  11000, total time:      4906.4 ms [min:       0.2, max:      23.6, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.assignments.PathSegmentEvaluation.evaluate:                                                count:  77000, total time:      9221.3 ms [min:       0.0, max:      23.6, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.AssignedResourceObjectConstruction.evaluate:                                  count:  25000, total time:     14916.6 ms [min:       0.0, max:      30.6, avg:       0.6]
  com.evolveum.midpoint.model.impl.lens.construction.EvaluatedResourceObjectConstructionImpl.evaluate:                             count:  28000, total time:     15431.9 ms [min:       0.0, max:      30.6, avg:       0.6]
  com.evolveum.midpoint.model.impl.lens.construction.PlainResourceObjectConstruction.evaluate:                                     count:   3000, total time:       698.3 ms [min:       0.0, max:      16.2, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.ActivationProcessor.projectionActivation:                                        count:   6000, total time:        19.1 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateItem:                                          count:  14836, total time:       261.8 ms [min:       0.0, max:      16.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateValues:                                        count:   3000, total time:       482.0 ms [min:       0.0, max:      16.1, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.determineFocusContext:                                             count:   3000, total time:     12868.2 ms [min:       0.0, max:      69.8, avg:       4.3]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.load:                                                              count:    500, total time:     49660.6 ms [min:      61.1, max:     296.3, avg:      99.3]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.loadProjection:                                                    count:   3000, total time:     23224.7 ms [min:       1.5, max:      50.1, avg:       7.7]
  com.evolveum.midpoint.model.impl.lens.projector.DependencyProcessor.sortProjectionsToWaves:                                      count:   1000, total time:         5.3 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ProjectionValuesProcessor.iteration:                                             count:   3000, total time:      1249.9 ms [min:       0.0, max:      16.5, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.activation:                                                            count:   1000, total time:        39.4 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignments:                                                           count:   1000, total time:     24210.8 ms [min:       5.2, max:      87.0, avg:      24.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsConflicts:                                                  count:   1000, total time:         6.9 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsMembershipAndDelegate:                                      count:   1000, total time:        51.6 ms [min:       0.0, max:       0.3, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsOrg:                                                        count:   1000, total time:         6.4 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focus:                                                                 count:   1000, total time:    117765.0 ms [min:      61.4, max:     261.0, avg:     117.8]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusActivation:                                                       count:   3000, total time:        71.2 ms [min:       0.0, max:       1.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusCredentials:                                                      count:   1000, total time:         5.2 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:        13.2 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.inbound:                                                               count:   1000, total time:     93266.3 ms [min:      54.9, max:     199.4, avg:      93.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.load:                                                                  count:    500, total time:     49663.4 ms [min:      61.1, max:     296.3, avg:      99.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterAssignments:                                        count:   1000, total time:        15.5 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterProjections:                                        count:   1000, total time:        12.1 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateBeforeAssignments:                                       count:   1000, total time:        16.3 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.project:                                                               count:   1000, total time:    170007.1 ms [min:      61.4, max:     511.5, avg:     170.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectProjection:                                                     count:   3000, total time:      2456.9 ms [min:       0.2, max:      17.1, avg:       0.8]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projection:                                                            count:   6000, total time:      2474.2 ms [min:       0.0, max:      17.1, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionCredentials:                                                 count:   3000, total time:        26.2 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionLifecycle:                                                   count:   3000, total time:         9.3 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionReconciliation:                                              count:   3000, total time:       935.5 ms [min:       0.1, max:      12.3, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValues:                                                      count:   3000, total time:      1275.1 ms [min:       0.0, max:      16.5, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValuesPostRecon:                                             count:   3000, total time:       126.5 ms [min:       0.0, max:       0.2, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.distributeConstructions:                               count:   1000, total time:        36.5 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.evaluateFocusMappings:                                 count:   1000, total time:        12.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:     24205.4 ms [min:       5.2, max:      87.0, avg:      24.2]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processProjections:                                    count:   1000, total time:     15029.1 ms [min:       0.1, max:      61.3, avg:      15.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentTripleEvaluator.evaluateAssignment:                              count:  11000, total time:      5066.9 ms [min:       0.2, max:      23.6, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.projector.focus.PruningOperation.execute:                                                  count:   1000, total time:         2.7 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:      2598.2 ms [min:       1.6, max:      28.2, avg:       2.6]
  com.evolveum.midpoint.model.impl.lens.projector.policy.PolicyRuleEnforcer.execute:                                               count:    500, total time:         5.0 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.sync.RecomputeTaskHandler.handle:                                                               count:    500, total time:    185557.2 ms [min:     258.5, max:     653.6, avg:     371.1]
  com.evolveum.midpoint.notifications.impl.NotificationHook.invoke:                                                                count:   2000, total time:         2.5 ms [min:       0.0, max:       0.5, avg:       0.0]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.getObject:                                                            count:  12000, total time:     48343.7 ms [min:       0.0, max:      53.7, avg:       4.0]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjects:                                                        count:  10000, total time:      2396.8 ms [min:       0.2, max:      12.3, avg:       0.2]
  com.evolveum.midpoint.provisioning.impl.AccessChecker.accessCheck:                                                               count:   6000, total time:       208.4 ms [min:       0.0, max:       1.0, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ProvisioningContext.getConnectorInstance:                                                count:   6000, total time:       155.7 ms [min:       0.0, max:       8.8, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ShadowCache.handleObject:                                                                count:  10000, total time:       202.2 ms [min:       0.0, max:      12.1, avg:       0.0]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.fetchObject:                                                        count:   6000, total time:      3350.7 ms [min:       0.2, max:      26.3, avg:       0.6]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.search:                                                             count:   5000, total time:     12541.6 ms [min:       0.6, max:      40.1, avg:       2.5]
  com.evolveum.midpoint.provisioning.ucf.impl.connid.ConnIdConvertor.convertToResourceObject:                                      count:  25552, total time:      1291.9 ms [min:       0.0, max:      19.7, avg:       0.1]
  com.evolveum.midpoint.repo.api.RepositoryService.countObjects:                                                                   count:      1, total time:         9.3 ms [min:       9.3, max:       9.3, avg:       9.3]
  com.evolveum.midpoint.repo.api.RepositoryService.getObject:                                                                      count:   3199, total time:     14528.1 ms [min:       0.4, max:      69.8, avg:       4.5]
  com.evolveum.midpoint.repo.api.RepositoryService.getVersion:                                                                     count:      8, total time:         3.9 ms [min:       0.4, max:       0.6, avg:       0.5]
  com.evolveum.midpoint.repo.api.RepositoryService.modifyObject:                                                                   count:     17, total time:       258.7 ms [min:       4.5, max:      22.7, avg:      15.2]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjects:                                                                  count:  21086, total time:     31435.8 ms [min:       0.4, max:    1349.4, avg:       1.5]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjectsIterative:                                                         count:      1, total time:     33338.9 ms [min:   33338.9, max:   33338.9, avg:   33338.9]
  com.evolveum.midpoint.repo.cache.RepositoryCache.countObjects:                                                                   count:      1, total time:         9.6 ms [min:       9.6, max:       9.6, avg:       9.6]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getObject:                                                                      count:  44501, total time:     17324.5 ms [min:       0.0, max:      69.8, avg:       0.4]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getVersion:                                                                     count:  34033, total time:       178.0 ms [min:       0.0, max:      14.0, avg:       0.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchObjects:                                                                  count:  39552, total time:     17780.8 ms [min:       0.0, max:      29.7, avg:       0.4]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchObjectsIterative:                                                         count:      1, total time:     33339.9 ms [min:   33339.9, max:   33339.9, avg:   33339.9]
  com.evolveum.midpoint.task.api.Task.getPathToRootTask:                                                                           count:   1500, total time:      5720.6 ms [min:       1.2, max:      26.9, avg:       3.8]
  com.evolveum.midpoint.task.api.TaskManager.createTaskInstance:                                                                   count:   1504, total time:       948.3 ms [min:       0.4, max:      23.8, avg:       0.6]
  com.evolveum.midpoint.task.api.TaskManager.getTaskByIdentifier:                                                                  count:   1500, total time:      5711.1 ms [min:       1.2, max:      26.9, avg:       3.8]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskPlain:                                                        count:      4, total time:        15.3 ms [min:       1.3, max:       8.7, avg:       3.8]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskTypeByIdentifier:                                             count:   1500, total time:      4752.6 ms [min:       0.7, max:      26.2, avg:       3.2]
  com.evolveum.midpoint.wf.impl.hook.WfHook.invoke:                                                                                count:   2000, total time:        19.2 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.wf.impl.processors.primary.PrimaryChangeProcessor.previewOrProcessModelInvocation:                         count:    500, total time:         1.2 ms [min:       0.0, max:       0.0, avg:       0.0]
  interface com.evolveum.midpoint.notifications.api.NotificationManager.processEvent:                                              count:      1, total time:         5.9 ms [min:       5.9, max:       5.9, avg:       5.9]
  org.identityconnectors.framework.api.ConnectorFacade.getObject:                                                                  count:   6000, total time:      1605.9 ms [min:       0.1, max:      26.1, avg:       0.3]
  org.identityconnectors.framework.api.ConnectorFacade.search:                                                                     count:   5000, total time:     11454.6 ms [min:       0.5, max:      39.9, avg:       2.3]



