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

Iterative task information:
  Successfully processed:    500 in     910556 ms =   1821.1 ms per object, last: ShadowType:u-00000498 (u-00000498 (ACCOUNT - default - AccountObjectClass), ad694888-74a9-43e9-8284-2e304efc950e) on Tue May 04 04:43:44 CEST 2021 in 1399 ms
  Failed:                      0 in          0 ms =      0.0 ms per object

Actions executed:
  All object actions:
    ADD        UserType                       http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:    500 time(s), last: u-00000498 (User null (u-00000498), d79f5181-cd81-45f1-9b53-5035398c306f) on Tue May 04 04:43:43 CEST 2021
    ADD        ShadowType                     http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:  10000 time(s), last: null (Shadow null (ACCOUNT - default - AccountObjectClass), b62af27f-6671-45aa-b2e7-c8bb07ec2e54) on Tue May 04 04:43:44 CEST 2021
    MODIFY     UserType                       http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:  10500 time(s), last: u-00000498 (User null (u-00000498), d79f5181-cd81-45f1-9b53-5035398c306f) on Tue May 04 04:43:44 CEST 2021
    MODIFY     ShadowType                     http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:   1000 time(s), last: u-00000498 (Shadow u-00000498 (ACCOUNT - default - AccountObjectClass), ad694888-74a9-43e9-8284-2e304efc950e) on Tue May 04 04:43:43 CEST 2021
  Resulting object actions:
    ADD        ShadowType                     http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:  10000 time(s), last: null (Shadow null (ACCOUNT - default - AccountObjectClass), b05288ae-3864-4e7c-b3a8-d73f6740cbe9) on Tue May 04 04:43:44 CEST 2021
    ADD        UserType                       http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:    500 time(s), last: u-00000498 (User null (u-00000498), d79f5181-cd81-45f1-9b53-5035398c306f) on Tue May 04 04:43:43 CEST 2021
    MODIFY     ShadowType                     http://midpoint.evolveum.com/xml/ns/public/common/channels-3#import
      success:    500 time(s), last: u-00000498 (Shadow u-00000498 (ACCOUNT - default - AccountObjectClass), ad694888-74a9-43e9-8284-2e304efc950e) on Tue May 04 04:43:43 CEST 2021

Environmental performance information:
  Provisioning:
    source-000 (AccountObjectClass)                              count:        1, total time:      117 ms [min:      0, max:     12, avg:   117.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-002 (AccountObjectClass)                              count:      500, total time:      128 ms [min:      0, max:      2, 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-007 (AccountObjectClass)                              count:      500, total time:      131 ms [min:      0, max:     10, 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-005 (AccountObjectClass)                              count:      500, total time:       47 ms [min:      0, max:      1, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-001 (AccountObjectClass)                              count:      500, total time:       77 ms [min:      0, max:      1, avg:     0.2] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-004 (AccountObjectClass)                              count:      500, total time:      121 ms [min:      0, max:     12, avg:     0.2] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-016 (AccountObjectClass)                              count:      500, total time:      139 ms [min:      0, max:      1, 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-011 (AccountObjectClass)                              count:      500, total time:       63 ms [min:      0, max:      2, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-019 (AccountObjectClass)                              count:      500, total time:       92 ms [min:      0, max:      1, avg:     0.2] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-018 (AccountObjectClass)                              count:      500, total time:      181 ms [min:      0, max:     14, avg:     0.4] 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:       87 ms [min:      0, max:      1, avg:     0.2] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-008 (AccountObjectClass)                              count:      500, total time:       91 ms [min:      0, max:     29, 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-006 (AccountObjectClass)                              count:      500, total time:       59 ms [min:      0, max:      5, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-009 (AccountObjectClass)                              count:      500, total time:      128 ms [min:      0, max:      1, 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-010 (AccountObjectClass)                              count:      500, total time:      105 ms [min:      0, max:      9, 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-015 (AccountObjectClass)                              count:      500, total time:      139 ms [min:      0, max:      2, 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-014 (AccountObjectClass)                              count:      500, total time:      107 ms [min:      0, max:     13, 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 (AccountObjectClass)                              count:      500, total time:       46 ms [min:      0, max:      2, avg:     0.1] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-017 (AccountObjectClass)                              count:      500, total time:      100 ms [min:      0, max:      3, 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-013 (AccountObjectClass)                              count:      500, total time:      111 ms [min:      0, max:      1, avg:     0.2] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-012 (AccountObjectClass)                              count:      500, total time:      121 ms [min:      0, max:      1, avg:     0.2] GET:     0:  0 SEARCH:     0:  0 CREATE:   500:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
  Mappings:
    target-004                               count:    500, total time:     60 ms [min:     0, max:    17, avg:     0.1]
    target-008                               count:    500, total time:     33 ms [min:     0, max:     1, avg:     0.1]
    target-001                               count:    500, total time:     35 ms [min:     0, max:     1, avg:     0.1]
    source-000                               count: 103000, total time:  79990 ms [min:     0, max:   310, avg:     0.8]
    target-002                               count:    500, total time:     57 ms [min:     0, max:     6, avg:     0.1]
    target-007                               count:    500, total time:     49 ms [min:     0, max:     7, avg:     0.1]
    target-013                               count:    500, total time:     37 ms [min:     0, max:     1, avg:     0.1]
    target-011                               count:    500, total time:     34 ms [min:     0, max:     1, avg:     0.1]
    target-000                               count:    500, total time:     64 ms [min:     0, max:    22, avg:     0.1]
    target-006                               count:    500, total time:     50 ms [min:     0, max:     1, avg:     0.1]
    target-003                               count:    500, total time:     30 ms [min:     0, max:     1, avg:     0.1]
    target-018                               count:    500, total time:     36 ms [min:     0, max:     1, avg:     0.1]
    target-014                               count:    500, total time:     53 ms [min:     0, max:     1, avg:     0.1]
    target-017                               count:    500, total time:     46 ms [min:     0, max:     1, avg:     0.1]
    target-009                               count:    500, total time:     30 ms [min:     0, max:     1, avg:     0.1]
    target-012                               count:    500, total time:     38 ms [min:     0, max:     2, avg:     0.1]
    target-010                               count:    500, total time:     44 ms [min:     0, max:     2, avg:     0.1]
    target-005                               count:    500, total time:     42 ms [min:     0, max:     1, avg:     0.1]
    target-016                               count:    500, total time:     40 ms [min:     0, max:     1, avg:     0.1]
    target-019                               count:    500, total time:     31 ms [min:     0, max:     1, avg:     0.1]
    target-015                               count:    500, total time:     34 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:ab4ecf18-eb7b-44d7-b3d9-d9abb085d9d6(source-000) in 1 ms.
  On:           2021-05-04T04:43:44.952+02:00

Repository performance information:
  addObject.ShadowType:                count:  10500, total time:    43671 ms [min:     2, max:    49, avg:     4.2], wasted time for    4 retry/retries:      104 ms [min:     0, max:    46, avg:     0.0] (  0.24%)
  addObject.UserType:                  count:    500, total time:     3772 ms [min:     5, max:    99, avg:     7.5], wasted time for    3 retry/retries:      171 ms [min:     0, max:    89, avg:     0.3] (  4.53%)
  fetchExtItems.RExtItem:              count:      5, total time:        8 ms [min:     1, max:     2, avg:     1.6]
  getObject.ArchetypeType:             count:      3, total time:        3 ms [min:     0, max:     2, avg:     1.0]
  getObject.ObjectTemplateType:        count:      3, total time:        3 ms [min:     0, max:     2, avg:     1.0]
  getObject.RoleType:                  count:      4, total time:       13 ms [min:     2, max:     4, avg:     3.3]
  getObject.ShadowType:                count:  21500, total time:    13632 ms [min:     0, max:    52, avg:     0.6]
  getObject.SystemConfigurationType:   count:      1, total time:        2 ms [min:     2, max:     2, avg:     2.0]
  getObject.TaskType:                  count:      4, total time:       24 ms [min:     2, max:    16, avg:     6.0]
  getObject.UserType:                  count:   2504, total time:    25779 ms [min:     0, max:    72, avg:    10.3]
  getVersion.ResourceType:             count:     42, total time:       20 ms [min:     0, max:     1, avg:     0.5]
  getVersion.SystemConfigurationType:  count:      2, total time:        0 ms [min:     0, max:     0, avg:     0.0]
  modifyObject.ShadowType:             count:  12000, total time:    61105 ms [min:     3, max:   506, avg:     5.1], wasted time for  127 retry/retries:     4859 ms [min:     0, max:   497, avg:     0.4] (  7.95%)
  modifyObject.TaskType:               count:     51, total time:     1320 ms [min:     8, max:    47, avg:    25.9]
  modifyObject.UserType:               count:  10500, total time:   379655 ms [min:    23, max:  2217, avg:    36.2], wasted time for  134 retry/retries:    14135 ms [min:     0, max:  2158, avg:     1.3] (  3.72%)
  searchObjects.FunctionLibraryType:   count:      1, total time:        4 ms [min:     4, max:     4, avg:     4.0]
  searchObjects.ShadowType:            count:    500, total time:      817 ms [min:     0, max:    30, avg:     1.6]
  searchObjects.TaskType:              count:   1500, total time:     6762 ms [min:     2, max:    57, avg:     4.5]
  searchObjects.UserType:              count:    500, total time:      322 ms [min:     0, max:     5, avg:     0.6]
  searchShadowOwner.FocusType:         count:    500, total time:      391 ms [min:     0, max:    17, avg:     0.8]

Cache performance information:
  com.evolveum.midpoint.provisioning.impl.ResourceCache:                               hits:  52004 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ArchetypeType:             hits:    497 ( 99.4%), weak hits:      0 (0.0%), misses:      3, 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:    496 ( 99.2%), weak hits:      0 (0.0%), misses:      4, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ShadowType:                hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:  21500, 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.ShadowType:                 hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, 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:  10462 ( 99.6%), weak hits:      0 (0.0%), misses:     42, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalVersionCache.SystemConfigurationType:  hits:    131 ( 98.5%), 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:    500 ( 50.0%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ShadowType:                  hits:  11000 ( 33.8%), weak hits:      0 (0.0%), misses:  21500, 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.ShadowType:                   hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, 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:  41500 ( 79.8%), weak hits:      0 (0.0%), misses:  10500, passes:      0, not available:      4
  com.evolveum.midpoint.repo.cache.local.LocalVersionCache.SystemConfigurationType:    hits:      8 (  5.7%), weak hits:      0 (0.0%), misses:    133, passes:      0, not available:      0

Methods performance information:
  class com.evolveum.midpoint.model.common.stringpolicy.ObjectValuePolicyEvaluator.validateValue:                                  count:   1500, total time:         1.8 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: 227500, total time:     49727.8 ms [min:       0.0, max:     294.5, avg:       0.2]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.SingleShotEvaluation.evaluateExpression:                  count: 125500, total time:       895.5 ms [min:       0.0, max:      25.1, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.ValueTupleTransformation.evaluate:                        count: 302000, total time:     24742.4 ms [min:       0.0, max:     288.0, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpression.evaluate:                                                  count: 425500, total time:      2873.1 ms [min:       0.0, max:     287.1, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpressionFactory.searchCustomFunctions:                              count:      1, total time:         7.1 ms [min:       7.1, max:       7.1, avg:       7.1]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluate:                                                                 count: 113000, total time:     79774.7 ms [min:       0.0, max:     308.6, avg:       0.7]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluatePrepared:                                                         count: 113000, total time:     53184.8 ms [min:       0.0, max:     308.2, avg:       0.5]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.prepare:                                                                  count: 113000, total time:     25475.8 ms [min:       0.0, max:      28.4, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute:                                                                    count:   1000, total time:    531113.0 ms [min:       0.1, max:    4307.8, avg:     531.1]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.focus.UserType:                                                     count:   1000, total time:      7628.7 ms [min:       0.1, max:     108.3, avg:       7.6]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.projection.ShadowType:                                              count:  10500, total time:     55438.2 ms [min:       3.4, max:      70.7, avg:       5.3]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.executeDelta:                                                               count:  11000, total time:     58192.2 ms [min:       3.3, max:     100.6, avg:       5.3]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.linkShadow:                                                                 count:  10500, total time:    380470.3 ms [min:      22.9, max:    2217.3, avg:      36.2]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.updateSituationInShadow:                                                    count:  10500, total time:     86936.2 ms [min:       5.4, max:     131.1, avg:       8.3]
  com.evolveum.midpoint.model.impl.lens.Clockwork.authorizeRequest:                                                                count:    500, total time:        19.1 ms [min:       0.0, max:       1.2, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.Clockwork.click:                                                                           count:   2500, total time:    903766.7 ms [min:       3.8, max:    4498.3, avg:     361.5]
  com.evolveum.midpoint.model.impl.lens.Clockwork.execution:                                                                       count:   1000, total time:    531121.5 ms [min:       0.1, max:    4307.8, avg:     531.1]
  com.evolveum.midpoint.model.impl.lens.Clockwork.run:                                                                             count:    500, total time:    904014.6 ms [min:    1354.0, max:    5907.8, avg:    1808.0]
  com.evolveum.midpoint.model.impl.lens.IvwoConsolidator.consolidateToDelta:                                                       count: 132500, total time:      1131.0 ms [min:       0.0, max:      38.5, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.assignments.AssignmentEvaluator.evaluate:                                                  count:   1000, total time:      1528.9 ms [min:       0.7, max:      35.1, avg:       1.5]
  com.evolveum.midpoint.model.impl.lens.assignments.PathSegmentEvaluation.evaluate:                                                count:  22000, total time:      3404.8 ms [min:       0.0, max:      34.9, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.construction.AssignedResourceObjectConstruction.evaluate:                                  count:  20000, total time:      1234.6 ms [min:       0.0, max:      14.8, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.EvaluatedResourceObjectConstructionImpl.evaluate:                             count:  30500, total time:      2589.4 ms [min:       0.0, max:      27.7, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.PlainResourceObjectConstruction.evaluate:                                     count:  10500, total time:      2684.9 ms [min:       0.0, max:      27.7, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.projector.ActivationProcessor.projectionActivation:                                        count:  21000, total time:       986.9 ms [min:       0.0, max:      14.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateItem:                                          count:  20000, total time:       321.2 ms [min:       0.0, max:      17.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateValues:                                        count:  10500, total time:     10922.6 ms [min:       0.4, max:      43.2, avg:       1.0]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.determineFocusContext:                                             count:   3500, total time:     25891.2 ms [min:       0.0, max:      72.5, avg:       7.4]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.load:                                                              count:   1000, total time:     27452.0 ms [min:       0.1, max:      98.1, avg:      27.5]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.loadProjection:                                                    count:  11000, total time:     27314.6 ms [min:       0.0, max:      35.2, avg:       2.5]
  com.evolveum.midpoint.model.impl.lens.projector.DependencyProcessor.sortProjectionsToWaves:                                      count:   1000, total time:        37.3 ms [min:       0.0, max:       7.9, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ProjectionValuesProcessor.iteration:                                             count:  10500, total time:     40262.0 ms [min:       1.5, max:      46.6, avg:       3.8]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.activation:                                                            count:   1000, total time:      1181.0 ms [min:       0.0, max:      18.3, avg:       1.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignments:                                                           count:   1000, total time:     10693.2 ms [min:       5.0, max:     122.5, avg:      10.7]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsConflicts:                                                  count:   1000, total time:        44.4 ms [min:       0.0, max:       7.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsMembershipAndDelegate:                                      count:   1000, total time:       910.1 ms [min:       0.1, max:      21.3, avg:       0.9]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsOrg:                                                        count:   1000, total time:        13.9 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focus:                                                                 count:   1000, total time:    179312.4 ms [min:      54.5, max:    1082.6, avg:     179.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusActivation:                                                       count:   3000, total time:       558.6 ms [min:       0.0, max:      18.2, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusCredentials:                                                      count:   1000, total time:        66.0 ms [min:       0.0, max:      18.1, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusLifecycle:                                                        count:   1000, total time:         3.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusPolicyRules:                                                      count:   1000, total time:        24.6 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.inbound:                                                               count:   1000, total time:    163390.9 ms [min:      48.5, max:     973.8, avg:     163.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.load:                                                                  count:   1000, total time:     27516.9 ms [min:       0.1, max:      98.3, avg:      27.5]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterAssignments:                                        count:   1000, total time:       830.0 ms [min:       0.1, max:      14.0, avg:       0.8]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterProjections:                                        count:   1000, total time:       871.6 ms [min:       0.1, max:      17.6, avg:       0.9]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateBeforeAssignments:                                       count:   1000, total time:       808.1 ms [min:       0.0, max:      32.0, avg:       0.8]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.project:                                                               count:   1000, total time:    253171.6 ms [min:      91.6, max:    1501.3, avg:     253.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectProjection:                                                     count:  10500, total time:     43972.4 ms [min:       2.0, max:      46.9, avg:       4.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projection:                                                            count:  21000, total time:     44053.5 ms [min:       0.0, max:      46.9, avg:       2.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionCredentials:                                                 count:  10500, total time:       510.1 ms [min:       0.0, max:      24.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionLifecycle:                                                   count:  10500, total time:      1000.3 ms [min:       0.0, max:      14.6, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionReconciliation:                                              count:  10500, total time:       424.1 ms [min:       0.0, max:      11.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValues:                                                      count:  10500, total time:     40879.9 ms [min:       1.6, max:      46.6, avg:       3.9]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValuesPostRecon:                                             count:  10500, total time:       565.3 ms [min:       0.0, max:      21.6, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.distributeConstructions:                               count:   1000, total time:       135.4 ms [min:       0.0, max:      12.4, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.evaluateFocusMappings:                                 count:   1000, total time:       588.4 ms [min:       0.0, max:      11.5, avg:       0.6]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processAssignments:                                    count:   1000, total time:     10684.6 ms [min:       5.0, max:     121.2, avg:      10.7]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processProjections:                                    count:   1000, total time:      1948.2 ms [min:       0.5, max:      54.9, avg:       1.9]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentTripleEvaluator.evaluateAssignment:                              count:   1000, total time:      1561.5 ms [min:       0.8, max:      35.2, avg:       1.6]
  com.evolveum.midpoint.model.impl.lens.projector.focus.PruningOperation.execute:                                                  count:   1000, total time:         4.3 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:      2253.7 ms [min:       1.1, max:      54.6, avg:       2.3]
  com.evolveum.midpoint.model.impl.lens.projector.policy.PolicyRuleEnforcer.execute:                                               count:    500, total time:         8.2 ms [min:       0.0, max:       0.6, avg:       0.0]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.notifyChange:                                                   count:   1000, total time:    912202.0 ms [min:       3.0, max:    6119.0, avg:     912.2]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.setupSituation:                                                 count:    500, total time:      1006.4 ms [min:       1.0, max:      23.0, avg:       2.0]
  com.evolveum.midpoint.model.impl.sync.SynchronizeAccountResultHandler.handle:                                                    count:    500, total time:    910564.0 ms [min:    1360.8, max:    6122.7, avg:    1821.1]
  com.evolveum.midpoint.model.impl.util.AuditHelper.audit:                                                                         count:   1000, total time:     88612.8 ms [min:       1.5, max:     287.5, avg:      88.6]
  com.evolveum.midpoint.model.impl.util.AuditHelper.resolveName:                                                                   count:  21000, total time:        68.0 ms [min:       0.0, max:      12.1, avg:       0.0]
  com.evolveum.midpoint.notifications.impl.NotificationHook.invoke:                                                                count:   2000, total time:         2.7 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.addObject:                                                            count:  10000, total time:     47913.0 ms [min:       3.2, max:      51.3, avg:       4.8]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.countObjects:                                                         count:      1, total time:         6.9 ms [min:       6.9, max:       6.9, avg:       6.9]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.getObject:                                                            count:  31002, total time:     53971.2 ms [min:       0.0, max:      54.0, avg:       1.7]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.modifyObject:                                                         count:  11000, total time:     62893.5 ms [min:       3.7, max:     127.4, avg:       5.7]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjectsIterative:                                               count:      1, total time:    148150.5 ms [min:  148150.5, max:  148150.5, avg:  148150.5]
  com.evolveum.midpoint.provisioning.impl.AccessChecker.accessCheck:                                                               count:  21500, total time:       177.7 ms [min:       0.0, max:       1.1, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ProvisioningContext.getConnectorInstance:                                                count:  30001, total time:       475.7 ms [min:       0.0, max:      15.5, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ResourceObjectConverter.addResourceObject:                                               count:  10000, total time:      3957.0 ms [min:       0.1, max:      28.7, avg:       0.4]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.addObject:                                                          count:  10000, total time:      2885.5 ms [min:       0.1, max:      28.6, avg:       0.3]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.search:                                                             count:      1, total time:    148146.5 ms [min:  148146.5, max:  148146.5, avg:  148146.5]
  com.evolveum.midpoint.provisioning.ucf.impl.connid.ConnIdConvertor.convertToResourceObject:                                      count:    500, total time:       281.5 ms [min:       0.2, max:      11.5, avg:       0.6]
  com.evolveum.midpoint.repo.api.RepositoryService.addObject:                                                                      count:  11000, total time:     47115.1 ms [min:       2.7, max:      47.5, avg:       4.3]
  com.evolveum.midpoint.repo.api.RepositoryService.getObject:                                                                      count:  24019, total time:     39438.5 ms [min:       0.3, max:      71.9, avg:       1.6]
  com.evolveum.midpoint.repo.api.RepositoryService.getVersion:                                                                     count:     44, total time:        17.5 ms [min:       0.2, max:       0.8, avg:       0.4]
  com.evolveum.midpoint.repo.api.RepositoryService.modifyObject:                                                                   count:  22551, total time:    422986.7 ms [min:       2.9, max:     100.8, avg:      18.8]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjects:                                                                  count:   2501, total time:      7950.8 ms [min:       0.3, max:      57.7, avg:       3.2]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjectsIterative:                                                         count:      1, total time:         6.7 ms [min:       6.7, max:       6.7, avg:       6.7]
  com.evolveum.midpoint.repo.api.RepositoryService.searchShadowOwner:                                                              count:    500, total time:       392.0 ms [min:       0.3, max:      16.3, avg:       0.8]
  com.evolveum.midpoint.repo.cache.RepositoryCache.addObject:                                                                      count:  11000, total time:     48060.9 ms [min:       2.7, max:     100.2, avg:       4.4]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getObject:                                                                      count:  38501, total time:     78606.0 ms [min:       0.0, max:      72.0, avg:       2.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getVersion:                                                                     count:  52145, total time:       363.4 ms [min:       0.0, max:      15.4, avg:       0.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.invalidateCacheEntries:                                                         count:  33500, total time:       763.2 ms [min:       0.0, max:      20.9, avg:       0.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.modifyObject:                                                                   count:  22500, total time:    441794.8 ms [min:       3.0, max:    2216.6, avg:      19.6]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchObjects:                                                                  count:   1000, total time:      1216.3 ms [min:       0.3, max:      29.9, avg:       1.2]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchShadowOwner:                                                              count:    500, total time:       400.1 ms [min:       0.3, max:      16.3, avg:       0.8]
  com.evolveum.midpoint.schema.result.searchResult:                                                                                count:    500, total time:    147702.0 ms [min:      10.4, max:    3187.5, avg:     295.4]
  com.evolveum.midpoint.task.api.Task.getPathToRootTask:                                                                           count:   1500, total time:      8025.1 ms [min:       3.4, max:      58.4, avg:       5.4]
  com.evolveum.midpoint.task.api.TaskManager.createTaskInstance:                                                                   count:   1504, total time:       961.4 ms [min:       0.4, max:       7.5, avg:       0.6]
  com.evolveum.midpoint.task.api.TaskManager.getTaskByIdentifier:                                                                  count:   1500, total time:      8012.8 ms [min:       3.4, max:      58.4, avg:       5.3]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskPlain:                                                        count:      4, total time:        29.9 ms [min:       3.6, max:      17.8, avg:       7.5]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskTypeByIdentifier:                                             count:   1500, total time:      7042.2 ms [min:       2.6, max:      57.8, avg:       4.7]
  com.evolveum.midpoint.wf.impl.hook.WfHook.invoke:                                                                                count:   2000, total time:        45.4 ms [min:       0.0, max:       1.1, avg:       0.0]
  com.evolveum.midpoint.wf.impl.processors.primary.PrimaryChangeProcessor.previewOrProcessModelInvocation:                         count:    500, total time:         5.0 ms [min:       0.0, max:       0.4, avg:       0.0]
  interface com.evolveum.midpoint.notifications.api.NotificationManager.processEvent:                                              count:      1, total time:         6.3 ms [min:       6.3, max:       6.3, avg:       6.3]
  org.identityconnectors.framework.api.ConnectorFacade.create:                                                                     count:  10000, total time:      2431.2 ms [min:       0.0, max:      28.5, avg:       0.2]
  org.identityconnectors.framework.api.ConnectorFacade.search:                                                                     count:      1, total time:    148141.4 ms [min:  148141.4, max:  148141.4, avg:  148141.4]



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

Iterative task information:
  Successfully processed:    500 in     215221 ms =    430.4 ms per object, last: ShadowType:u-00000499 (u-00000499 (ACCOUNT - default - AccountObjectClass), f6e4eb14-df6e-4249-952c-e0c23aeff756) on Tue May 04 04:44:22 CEST 2021 in 292 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), f6e4eb14-df6e-4249-952c-e0c23aeff756) on Tue May 04 04:44:21 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), f6e4eb14-df6e-4249-952c-e0c23aeff756) on Tue May 04 04:44:21 CEST 2021

Environmental performance information:
  Provisioning:
    source-000 (AccountObjectClass)                              count:        1, total time:       90 ms [min:      0, max:      1, avg:    90.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:
    target-004                               count:    500, total time:     62 ms [min:     0, max:     1, avg:     0.1]
    target-008                               count:    500, total time:     76 ms [min:     0, max:    18, avg:     0.2]
    target-001                               count:    500, total time:     65 ms [min:     0, max:     1, avg:     0.1]
    source-000                               count: 103000, total time:  77977 ms [min:     0, max:    32, avg:     0.8]
    target-002                               count:    500, total time:     68 ms [min:     0, max:     1, avg:     0.1]
    target-007                               count:    500, total time:     58 ms [min:     0, max:     1, avg:     0.1]
    target-013                               count:    500, total time:     48 ms [min:     0, max:     1, avg:     0.1]
    target-011                               count:    500, total time:     48 ms [min:     0, max:     1, avg:     0.1]
    target-000                               count:    500, total time:     52 ms [min:     0, max:     1, avg:     0.1]
    target-006                               count:    500, total time:     61 ms [min:     0, max:     1, avg:     0.1]
    target-003                               count:    500, total time:     59 ms [min:     0, max:     1, avg:     0.1]
    target-018                               count:    500, total time:     56 ms [min:     0, max:     1, avg:     0.1]
    target-014                               count:    500, total time:     52 ms [min:     0, max:     1, avg:     0.1]
    target-017                               count:    500, total time:     70 ms [min:     0, max:     1, avg:     0.1]
    target-009                               count:    500, total time:     54 ms [min:     0, max:     1, avg:     0.1]
    target-012                               count:    500, total time:     57 ms [min:     0, max:     1, avg:     0.1]
    target-010                               count:    500, total time:     48 ms [min:     0, max:     1, avg:     0.1]
    target-005                               count:    500, total time:     51 ms [min:     0, max:     1, avg:     0.1]
    target-016                               count:    500, total time:     66 ms [min:     0, max:     1, avg:     0.1]
    target-019                               count:    500, total time:     58 ms [min:     0, max:     1, avg:     0.1]
    target-015                               count:    500, total time:     59 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:ab4ecf18-eb7b-44d7-b3d9-d9abb085d9d6(source-000) in 0 ms.
  On:           2021-05-04T04:44:22.121+02:00

Repository performance information:
  getObject.ArchetypeType:       count:      1, total time:        0 ms [min:     0, max:     0, avg:     0.0]
  getObject.ObjectTemplateType:  count:      1, total time:        0 ms [min:     0, max:     0, avg:     0.0]
  getObject.RoleType:            count:      1, total time:        2 ms [min:     2, max:     2, avg:     2.0]
  getObject.ShadowType:          count:   8360, total time:     4567 ms [min:     0, max:    25, avg:     0.5]
  getObject.TaskType:            count:      4, total time:       12 ms [min:     1, max:     7, avg:     3.0]
  getObject.UserType:            count:   2004, total time:    12797 ms [min:     0, max:    67, avg:     6.4]
  getVersion.ResourceType:       count:     21, total time:        7 ms [min:     0, max:     1, avg:     0.3]
  modifyObject.ShadowType:       count:    500, total time:     2358 ms [min:     3, max:    42, avg:     4.7]
  modifyObject.TaskType:         count:     18, total time:      349 ms [min:     5, max:    45, avg:    19.4]
  searchObjects.ShadowType:      count:    500, total time:      979 ms [min:     1, max:    23, avg:     2.0]
  searchObjects.TaskType:        count:   1500, total time:     3817 ms [min:     0, max:    19, avg:     2.5]
  searchShadowOwner.FocusType:   count:    500, total time:    12108 ms [min:    19, max:    50, avg:    24.2]

Cache performance information:
  com.evolveum.midpoint.provisioning.impl.ResourceCache:                               hits:  30504 (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:    499 ( 99.8%), weak hits:      0 (0.0%), misses:      1, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ShadowType:                hits:   2140 ( 20.4%), weak hits:      0 (0.0%), misses:   8360, 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.ShadowType:                 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:  10483 ( 99.8%), weak hits:      0 (0.0%), misses:     21, 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:    500 ( 50.0%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ShadowType:                  hits:  10000 ( 48.8%), weak hits:      0 (0.0%), misses:  10500, 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.ShadowType:                   hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:      0, passes:    500, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalVersionCache.ResourceType:               hits:  20000 ( 65.6%), weak hits:      0 (0.0%), misses:  10500, 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: 353000, total time:     48574.5 ms [min:       0.0, max:      32.1, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.SingleShotEvaluation.evaluateExpression:                  count: 251000, total time:      1682.2 ms [min:       0.0, max:      25.2, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.ValueTupleTransformation.evaluate:                        count: 302000, total time:     22983.0 ms [min:       0.0, max:      24.7, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpression.evaluate:                                                  count: 551000, total time:      1909.1 ms [min:       0.0, max:      25.2, avg:       0.0]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluate:                                                                 count: 113000, total time:     78444.7 ms [min:       0.0, max:      32.3, avg:       0.7]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluatePrepared:                                                         count: 113000, total time:     52601.8 ms [min:       0.0, max:      32.3, avg:       0.5]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.prepare:                                                                  count: 113000, total time:     24872.1 ms [min:       0.0, max:      27.8, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute:                                                                    count:   1000, total time:      1949.9 ms [min:       0.0, max:      26.8, avg:       1.9]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.projection.ShadowType:                                              count:  10500, total time:      1879.6 ms [min:       0.0, max:      22.8, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.updateSituationInShadow:                                                    count:  10500, total time:      1822.0 ms [min:       0.0, max:      22.8, avg:       0.2]
  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:    196571.7 ms [min:       0.2, max:     387.3, avg:      78.6]
  com.evolveum.midpoint.model.impl.lens.Clockwork.execution:                                                                       count:   1000, total time:      1954.6 ms [min:       0.0, max:      26.8, avg:       2.0]
  com.evolveum.midpoint.model.impl.lens.Clockwork.run:                                                                             count:    500, total time:    196717.0 ms [min:     262.3, max:     560.5, avg:     393.4]
  com.evolveum.midpoint.model.impl.lens.IvwoConsolidator.consolidateToDelta:                                                       count: 122500, total time:       801.6 ms [min:       0.0, max:      17.6, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.assignments.AssignmentEvaluator.evaluate:                                                  count:   1000, total time:      1140.0 ms [min:       0.7, max:      20.8, avg:       1.1]
  com.evolveum.midpoint.model.impl.lens.assignments.PathSegmentEvaluation.evaluate:                                                count:  22000, total time:      2536.2 ms [min:       0.0, max:      20.8, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.AssignedResourceObjectConstruction.evaluate:                                  count:  20000, total time:       166.1 ms [min:       0.0, max:      16.6, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.construction.EvaluatedResourceObjectConstructionImpl.evaluate:                             count:  30500, total time:      2569.7 ms [min:       0.0, max:      17.8, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.PlainResourceObjectConstruction.evaluate:                                     count:  10500, total time:      2563.8 ms [min:       0.0, max:      17.8, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.ActivationProcessor.projectionActivation:                                        count:  21000, total time:        77.7 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateItem:                                          count:  10000, total time:       103.5 ms [min:       0.0, max:       0.5, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateValues:                                        count:  10500, total time:       347.4 ms [min:       0.0, max:      17.5, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.determineFocusContext:                                             count:   3000, total time:     12020.3 ms [min:       0.0, max:      67.4, avg:       4.0]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.load:                                                              count:    500, total time:     22893.2 ms [min:       4.2, max:      92.4, avg:      45.8]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.loadProjection:                                                    count:  10500, total time:       826.7 ms [min:       0.0, max:      26.7, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.DependencyProcessor.sortProjectionsToWaves:                                      count:   1000, total time:         1.4 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ProjectionValuesProcessor.iteration:                                             count:  10500, total time:      3222.9 ms [min:       0.0, max:      17.9, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.activation:                                                            count:   1000, total time:       941.5 ms [min:       0.0, max:       2.9, avg:       0.9]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignments:                                                           count:   1000, total time:      4835.2 ms [min:       2.5, max:      28.6, avg:       4.8]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsConflicts:                                                  count:   1000, total time:        20.6 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsMembershipAndDelegate:                                      count:   1000, total time:        12.3 ms [min:       0.0, max:       0.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsOrg:                                                        count:   1000, total time:         6.2 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focus:                                                                 count:   1000, total time:    152872.5 ms [min:      52.5, max:     263.3, avg:     152.9]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusActivation:                                                       count:   3000, total time:        49.7 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusCredentials:                                                      count:   1000, total time:         6.5 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusLifecycle:                                                        count:   1000, total time:         1.8 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusPolicyRules:                                                      count:   1000, total time:        12.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.inbound:                                                               count:   1000, total time:    147787.7 ms [min:      48.7, max:     257.2, avg:     147.8]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.load:                                                                  count:    500, total time:     22898.0 ms [min:       4.2, max:      92.4, avg:      45.8]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterAssignments:                                        count:   1000, total time:        22.0 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterProjections:                                        count:   1000, total time:        18.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateBeforeAssignments:                                       count:   1000, total time:        26.2 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.project:                                                               count:   1000, total time:    180613.6 ms [min:      52.7, max:     340.8, avg:     180.6]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectProjection:                                                     count:  10500, total time:      3735.1 ms [min:       0.2, max:      18.0, avg:       0.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projection:                                                            count:  21000, total time:      3779.9 ms [min:       0.0, max:      18.0, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionCredentials:                                                 count:  10500, total time:        81.8 ms [min:       0.0, max:       3.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionLifecycle:                                                   count:  10500, total time:        18.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionReconciliation:                                              count:  10500, total time:       109.5 ms [min:       0.0, max:       0.4, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValues:                                                      count:  10500, total time:      3309.9 ms [min:       0.1, max:      18.0, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValuesPostRecon:                                             count:  10500, total time:         2.2 ms [min:       0.0, max:       0.2, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.distributeConstructions:                               count:   1000, total time:        79.8 ms [min:       0.0, max:       0.5, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.evaluateFocusMappings:                                 count:   1000, total time:        13.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:      4829.4 ms [min:       2.5, max:      28.6, avg:       4.8]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processProjections:                                    count:   1000, total time:       305.7 ms [min:       0.1, max:      17.0, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentTripleEvaluator.evaluateAssignment:                              count:   1000, total time:      1168.3 ms [min:       0.7, max:      20.8, avg:       1.2]
  com.evolveum.midpoint.model.impl.lens.projector.focus.PruningOperation.execute:                                                  count:   1000, total time:         1.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.consolidation.DeltaSetTripleMapConsolidation.consolidate:                  count:   1000, total time:      2092.4 ms [min:       1.3, max:      26.2, avg:       2.1]
  com.evolveum.midpoint.model.impl.lens.projector.policy.PolicyRuleEnforcer.execute:                                               count:    500, total time:         3.8 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.notifyChange:                                                   count:    500, total time:    214709.1 ms [min:     291.3, max:     629.0, avg:     429.4]
  com.evolveum.midpoint.model.impl.sync.SynchronizationServiceImpl.setupSituation:                                                 count:    500, total time:     15261.4 ms [min:      24.6, max:      57.3, avg:      30.5]
  com.evolveum.midpoint.model.impl.sync.SynchronizeAccountResultHandler.handle:                                                    count:    500, total time:    215223.7 ms [min:     292.0, max:     631.0, avg:     430.4]
  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.2 ms [min:       0.2, max:       0.2, avg:       0.2]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.getObject:                                                            count:  30502, total time:     22246.7 ms [min:       0.0, max:      34.0, avg:       0.7]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.searchObjectsIterative:                                               count:      1, total time:     35041.9 ms [min:   35041.9, max:   35041.9, avg:   35041.9]
  com.evolveum.midpoint.provisioning.impl.AccessChecker.accessCheck:                                                               count:    500, total time:        94.5 ms [min:       0.1, max:       0.4, 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.ucf.api.ConnectorInstance.search:                                                             count:      1, total time:     35041.5 ms [min:   35041.5, max:   35041.5, avg:   35041.5]
  com.evolveum.midpoint.provisioning.ucf.impl.connid.ConnIdConvertor.convertToResourceObject:                                      count:    500, total time:       236.4 ms [min:       0.2, max:      31.7, avg:       0.5]
  com.evolveum.midpoint.repo.api.RepositoryService.getObject:                                                                      count:  10371, total time:     17371.9 ms [min:       0.3, max:      67.3, avg:       1.7]
  com.evolveum.midpoint.repo.api.RepositoryService.getVersion:                                                                     count:     21, total time:         7.4 ms [min:       0.2, max:       0.5, avg:       0.4]
  com.evolveum.midpoint.repo.api.RepositoryService.modifyObject:                                                                   count:    518, total time:      2698.8 ms [min:       3.7, max:      45.3, avg:       5.2]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjects:                                                                  count:   2000, total time:      4801.5 ms [min:       0.7, max:      23.2, avg:       2.4]
  com.evolveum.midpoint.repo.api.RepositoryService.searchShadowOwner:                                                              count:    500, total time:     12113.2 ms [min:      19.5, max:      50.1, avg:      24.2]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getObject:                                                                      count:  26001, total time:     33219.0 ms [min:       0.0, max:      67.4, avg:       1.3]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getVersion:                                                                     count:  30539, total time:       123.4 ms [min:       0.0, max:       3.3, avg:       0.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.invalidateCacheEntries:                                                         count:    500, total time:         7.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.modifyObject:                                                                   count:    500, total time:      2369.3 ms [min:       3.7, max:      42.0, avg:       4.7]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchObjects:                                                                  count:    500, total time:      1007.1 ms [min:       1.2, max:      23.2, avg:       2.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchShadowOwner:                                                              count:    500, total time:     15253.9 ms [min:      24.6, max:      57.3, avg:      30.5]
  com.evolveum.midpoint.schema.result.searchResult:                                                                                count:    500, total time:     34683.0 ms [min:       2.3, max:     352.4, avg:      69.4]
  com.evolveum.midpoint.task.api.Task.getPathToRootTask:                                                                           count:   1500, total time:      4882.5 ms [min:       1.2, max:      26.8, avg:       3.3]
  com.evolveum.midpoint.task.api.TaskManager.createTaskInstance:                                                                   count:   1504, total time:       818.9 ms [min:       0.4, max:      25.4, avg:       0.5]
  com.evolveum.midpoint.task.api.TaskManager.getTaskByIdentifier:                                                                  count:   1500, total time:      4873.6 ms [min:       1.2, max:      26.8, avg:       3.2]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskPlain:                                                        count:      4, total time:        15.6 ms [min:       1.2, max:       9.2, avg:       3.9]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskTypeByIdentifier:                                             count:   1500, total time:      4045.5 ms [min:       0.8, max:      19.6, avg:       2.7]
  com.evolveum.midpoint.wf.impl.hook.WfHook.invoke:                                                                                count:   2000, total time:        27.5 ms [min:       0.0, max:       0.3, avg:       0.0]
  com.evolveum.midpoint.wf.impl.processors.primary.PrimaryChangeProcessor.previewOrProcessModelInvocation:                         count:    500, total time:         1.7 ms [min:       0.0, max:       0.0, avg:       0.0]
  interface com.evolveum.midpoint.notifications.api.NotificationManager.processEvent:                                              count:      1, total time:         0.2 ms [min:       0.2, max:       0.2, avg:       0.2]
  org.identityconnectors.framework.api.ConnectorFacade.search:                                                                     count:      1, total time:     35040.8 ms [min:   35040.8, max:   35040.8, avg:   35040.8]



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

Iterative task information:
  Successfully processed:    500 in     169280 ms =    338.6 ms per object, last: null:u-00000072 (User null (u-00000072), fefd2a3f-f614-46d9-9954-62495eac4077) on Tue May 04 04:44:53 CEST 2021 in 303 ms
  Failed:                      0 in          0 ms =      0.0 ms per object

Actions executed:
  All object actions:
  Resulting object actions:

Environmental performance information:
  Provisioning:
    target-002 (GroupObjectClass)                                count:     1000, total time:     1011 ms [min:      0, max:     44, avg:     1.0] GET:     0:  0 SEARCH:  1000:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-007 (GroupObjectClass)                                count:     1000, total time:      896 ms [min:      0, max:     38, avg:     0.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:      807 ms [min:      0, max:     18, avg:     0.8] 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-018 (AccountObjectClass)                              count:     1000, total time:      308 ms [min:      0, max:     14, avg:     0.3] 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:      214 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-015 (GroupObjectClass)                                count:     1000, total time:      775 ms [min:      0, max:     15, avg:     0.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-016 (AccountObjectClass)                              count:     1000, total time:      251 ms [min:      0, max:     21, avg:     0.3] 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-005 (GroupObjectClass)                                count:     1000, total time:      617 ms [min:      0, max:     29, avg:     0.6] 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:      140 ms [min:      0, max:      3, 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-011 (GroupObjectClass)                                count:     1000, total time:      750 ms [min:      0, max:     23, avg:     0.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-019 (GroupObjectClass)                                count:     1000, total time:      748 ms [min:      0, max:     26, avg:     0.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-018 (GroupObjectClass)                                count:     1000, total time:      936 ms [min:      0, max:     35, avg:     0.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-013 (AccountObjectClass)                              count:     1000, total time:      189 ms [min:      0, max:     11, 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-002 (AccountObjectClass)                              count:     1000, total time:      241 ms [min:      0, max:     14, 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-006 (GroupObjectClass)                                count:     1000, total time:      706 ms [min:      0, max:     27, avg:     0.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-016 (GroupObjectClass)                                count:     1000, total time:      861 ms [min:      0, max:     23, avg:     0.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-015 (AccountObjectClass)                              count:     1000, total time:      214 ms [min:      0, max:      1, avg:     0.2] GET:  1000:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-001 (GroupObjectClass)                                count:     1000, total time:      727 ms [min:      0, max:     18, avg:     0.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-000 (GroupObjectClass)                                count:     1000, total time:      803 ms [min:      0, max:     59, avg:     0.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-010 (GroupObjectClass)                                count:     1000, total time:      780 ms [min:      0, max:     28, avg:     0.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-014 (GroupObjectClass)                                count:     1000, total time:      839 ms [min:      0, max:     42, avg:     0.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-009 (GroupObjectClass)                                count:     1000, total time:     1035 ms [min:      0, max:     58, avg:     1.0] GET:     0:  0 SEARCH:  1000:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-011 (AccountObjectClass)                              count:     1000, total time:      116 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-009 (AccountObjectClass)                              count:     1000, total time:      272 ms [min:      0, max:     40, avg:     0.3] 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-010 (AccountObjectClass)                              count:     1000, total time:      173 ms [min:      0, max:     11, 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-008 (AccountObjectClass)                              count:     1000, total time:       93 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-006 (AccountObjectClass)                              count:     1000, total time:      104 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-013 (GroupObjectClass)                                count:     1000, total time:      902 ms [min:      0, max:     27, avg:     0.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-017 (AccountObjectClass)                              count:     1000, total time:      154 ms [min:      0, max:      1, avg:     0.2] GET:  1000:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-019 (AccountObjectClass)                              count:     1000, total time:      140 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-012 (GroupObjectClass)                                count:     1000, total time:      787 ms [min:      0, max:     24, avg:     0.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:       73 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-014 (AccountObjectClass)                              count:     1000, total time:      177 ms [min:      0, max:      2, 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:      114 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-007 (AccountObjectClass)                              count:     1000, total time:      210 ms [min:      0, max:      6, 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-012 (AccountObjectClass)                              count:     1000, total time:      177 ms [min:      0, max:      1, avg:     0.2] GET:  1000:  0 SEARCH:     0:  0 CREATE:     0:  0 UPDATE:     0:  0 DELETE:     0:  0 SYNC:     0:  0 SCRIPT:     0:  0 OTHER:     0:  0
    target-003 (GroupObjectClass)                                count:     1000, total time:      746 ms [min:      0, max:     18, avg:     0.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-008 (GroupObjectClass)                                count:     1000, total time:      702 ms [min:      0, max:     25, avg:     0.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-004 (GroupObjectClass)                                count:     1000, total time:      916 ms [min:      0, max:     36, avg:     0.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-005 (AccountObjectClass)                              count:     1000, total time:      113 ms [min:      0, max:      6, 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-017 (GroupObjectClass)                                count:     1000, total time:      830 ms [min:      0, max:     28, avg:     0.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
  Mappings:
    target-004                               count:    500, total time:     52 ms [min:     0, max:     1, avg:     0.1]
    target-008                               count:    500, total time:     54 ms [min:     0, max:     1, avg:     0.1]
    target-001                               count:    500, total time:     60 ms [min:     0, max:     1, avg:     0.1]
    source-000                               count: 103000, total time:  52093 ms [min:     0, max:    34, avg:     0.5]
    target-002                               count:    500, total time:     92 ms [min:     0, max:    20, avg:     0.2]
    target-007                               count:    500, total time:     70 ms [min:     0, max:     1, avg:     0.1]
    target-013                               count:    500, total time:     62 ms [min:     0, max:     1, avg:     0.1]
    target-011                               count:    500, total time:     62 ms [min:     0, max:     1, avg:     0.1]
    target-000                               count:    500, total time:     62 ms [min:     0, max:     5, avg:     0.1]
    target-006                               count:    500, total time:     60 ms [min:     0, max:     1, avg:     0.1]
    target-003                               count:    500, total time:     55 ms [min:     0, max:     1, avg:     0.1]
    target-018                               count:    500, total time:     51 ms [min:     0, max:     1, avg:     0.1]
    target-014                               count:    500, total time:     59 ms [min:     0, max:     1, avg:     0.1]
    target-017                               count:    500, total time:     72 ms [min:     0, max:     1, avg:     0.1]
    target-009                               count:    500, total time:     50 ms [min:     0, max:     1, avg:     0.1]
    target-012                               count:    500, total time:     59 ms [min:     0, max:     1, avg:     0.1]
    target-010                               count:    500, total time:     57 ms [min:     0, max:     1, avg:     0.1]
    target-005                               count:    500, total time:     63 ms [min:     0, max:     1, avg:     0.1]
    target-016                               count:    500, total time:     63 ms [min:     0, max:     1, avg:     0.1]
    target-019                               count:    500, total time:     66 ms [min:     0, max:     2, avg:     0.1]
    target-015                               count:    500, total time:     64 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:ab4ecf18-eb7b-44d7-b3d9-d9abb085d9d6(source-000) in 0 ms.
  On:           2021-05-04T04:44:53.831+02:00

Repository performance information:
  countObjects.UserType:               count:      1, total time:        9 ms [min:     9, max:     9, avg:     9.0]
  getObject.ShadowType:                count:   1994, total time:     1484 ms [min:     0, max:    13, avg:     0.7]
  getObject.SystemConfigurationType:   count:      1, total time:        2 ms [min:     2, max:     2, avg:     2.0]
  getObject.TaskType:                  count:      4, total time:       18 ms [min:     1, max:    12, avg:     4.5]
  getObject.UserType:                  count:   2004, total time:    13407 ms [min:     0, max:    68, avg:     6.7]
  getVersion.SystemConfigurationType:  count:      1, total time:        0 ms [min:     0, max:     0, avg:     0.0]
  modifyObject.TaskType:               count:     17, total time:      336 ms [min:     4, max:    39, avg:    19.8]
  searchObjects.TaskType:              count:   1500, total time:     6504 ms [min:     0, max:    26, avg:     4.3]
  searchObjects.UserType:              count:     11, total time:    10822 ms [min:     2, max:  1158, avg:   983.8]

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:  84000 (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:    500 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalObjectCache.ShadowType:                hits:   8506 ( 81.0%), weak hits:      0 (0.0%), misses:   1994, 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.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:  10500 (100.0%), weak hits:      0 (0.0%), misses:      0, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.global.GlobalVersionCache.SystemConfigurationType:  hits:     28 ( 96.6%), weak hits:      0 (0.0%), misses:      1, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ArchetypeType:               hits:   3000 ( 85.7%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ObjectTemplateType:          hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.RoleType:                    hits:    500 ( 50.0%), weak hits:      0 (0.0%), misses:    500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalObjectCache.ShadowType:                  hits:  21000 ( 66.7%), weak hits:      0 (0.0%), misses:  10500, 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.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:  73500 ( 87.5%), weak hits:      0 (0.0%), misses:  10500, passes:      0, not available:      0
  com.evolveum.midpoint.repo.cache.local.LocalVersionCache.SystemConfigurationType:    hits:      0 (  0.0%), weak hits:      0 (0.0%), misses:     29, 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: 353000, total time:     47217.5 ms [min:       0.0, max:      33.0, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.SingleShotEvaluation.evaluateExpression:                  count: 251000, total time:      1638.3 ms [min:       0.0, max:      12.6, avg:       0.0]
  com.evolveum.midpoint.model.common.expression.evaluator.transformation.ValueTupleTransformation.evaluate:                        count: 302000, total time:     23302.2 ms [min:       0.0, max:      32.3, avg:       0.1]
  com.evolveum.midpoint.model.common.expression.script.ScriptExpression.evaluate:                                                  count: 551000, total time:      1861.6 ms [min:       0.0, max:      13.8, avg:       0.0]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluate:                                                                 count: 113000, total time:     52573.2 ms [min:       0.0, max:      33.1, avg:       0.5]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.evaluatePrepared:                                                         count: 113000, total time:     51157.4 ms [min:       0.0, max:      33.1, avg:       0.5]
  com.evolveum.midpoint.model.common.mapping.MappingImpl.prepare:                                                                  count: 113000, total time:       525.7 ms [min:       0.0, max:       0.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute:                                                                    count:   1000, total time:      1014.4 ms [min:       0.0, max:      14.3, avg:       1.0]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.execute.projection.ShadowType:                                              count:  10500, total time:       941.6 ms [min:       0.0, max:      12.2, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.ChangeExecutor.updateSituationInShadow:                                                    count:  10500, total time:       863.6 ms [min:       0.0, max:      12.2, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.Clockwork.authorizeRequest:                                                                count:    500, total time:         0.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.Clockwork.click:                                                                           count:   2500, total time:    169045.6 ms [min:       0.2, max:     496.2, avg:      67.6]
  com.evolveum.midpoint.model.impl.lens.Clockwork.execution:                                                                       count:   1000, total time:      1018.5 ms [min:       0.0, max:      14.3, avg:       1.0]
  com.evolveum.midpoint.model.impl.lens.Clockwork.run:                                                                             count:    500, total time:    169184.7 ms [min:     239.9, max:     589.5, avg:     338.4]
  com.evolveum.midpoint.model.impl.lens.IvwoConsolidator.consolidateToDelta:                                                       count: 132500, total time:       819.0 ms [min:       0.0, max:      12.6, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.assignments.AssignmentEvaluator.evaluate:                                                  count:   1000, total time:      1123.4 ms [min:       0.7, max:      11.6, avg:       1.1]
  com.evolveum.midpoint.model.impl.lens.assignments.PathSegmentEvaluation.evaluate:                                                count:  22000, total time:      2489.1 ms [min:       0.0, max:      11.5, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.AssignedResourceObjectConstruction.evaluate:                                  count:  20000, total time:       151.6 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.construction.EvaluatedResourceObjectConstructionImpl.evaluate:                             count:  30500, total time:      2585.4 ms [min:       0.0, max:      20.8, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.construction.PlainResourceObjectConstruction.evaluate:                                     count:  10500, total time:      2587.3 ms [min:       0.0, max:      20.8, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.ActivationProcessor.projectionActivation:                                        count:  21000, total time:        63.9 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateItem:                                          count:  20000, total time:       168.7 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ConsolidationProcessor.consolidateValues:                                        count:  10500, total time:       297.0 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.determineFocusContext:                                             count:   3000, total time:     12433.5 ms [min:       0.0, max:      67.5, avg:       4.1]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.load:                                                              count:    500, total time:     52113.9 ms [min:      50.3, max:     326.0, avg:     104.2]
  com.evolveum.midpoint.model.impl.lens.projector.ContextLoader.loadProjection:                                                    count:  10500, total time:     22720.0 ms [min:       0.9, max:      61.1, avg:       2.2]
  com.evolveum.midpoint.model.impl.lens.projector.DependencyProcessor.sortProjectionsToWaves:                                      count:   1000, total time:        17.8 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.ProjectionValuesProcessor.iteration:                                             count:  10500, total time:      3232.0 ms [min:       0.0, max:      20.9, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.activation:                                                            count:   1000, total time:       124.0 ms [min:       0.0, max:       0.3, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignments:                                                           count:   1000, total time:      6775.0 ms [min:       2.2, max:      28.3, avg:       6.8]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsConflicts:                                                  count:   1000, total time:        20.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsMembershipAndDelegate:                                      count:   1000, total time:         9.9 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.assignmentsOrg:                                                        count:   1000, total time:         6.0 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focus:                                                                 count:   1000, total time:     95360.5 ms [min:      57.5, max:     160.6, avg:      95.4]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusActivation:                                                       count:   3000, total time:        43.3 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusCredentials:                                                      count:   1000, total time:         5.0 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusLifecycle:                                                        count:   1000, total time:         1.9 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.focusPolicyRules:                                                      count:   1000, total time:        12.9 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.inbound:                                                               count:   1000, total time:     88334.3 ms [min:      52.6, max:     157.0, avg:      88.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.load:                                                                  count:    500, total time:     52117.4 ms [min:      50.3, max:     326.0, avg:     104.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterAssignments:                                        count:   1000, total time:        16.1 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateAfterProjections:                                        count:   1000, total time:        13.1 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.objectTemplateBeforeAssignments:                                       count:   1000, total time:        18.0 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.project:                                                               count:   1000, total time:    152747.7 ms [min:      57.6, max:     471.9, avg:     152.7]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectProjection:                                                     count:  10500, total time:      4976.1 ms [min:       0.2, max:      21.1, avg:       0.5]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projection:                                                            count:  21000, total time:      5024.1 ms [min:       0.0, max:      21.2, avg:       0.2]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionCredentials:                                                 count:  10500, total time:        78.5 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionLifecycle:                                                   count:  10500, total time:        21.6 ms [min:       0.0, max:       0.3, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionReconciliation:                                              count:  10500, total time:      1202.9 ms [min:       0.1, max:      13.6, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValues:                                                      count:  10500, total time:      3303.3 ms [min:       0.1, max:      20.9, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.projector.Projector.projectionValuesPostRecon:                                             count:  10500, total time:       126.8 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.distributeConstructions:                               count:   1000, total time:        70.9 ms [min:       0.0, max:       0.2, avg:       0.1]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.evaluateFocusMappings:                                 count:   1000, total time:        11.3 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processAssignments:                                    count:   1000, total time:      6769.0 ms [min:       2.2, max:      28.3, avg:       6.8]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentProcessor.processProjections:                                    count:   1000, total time:       281.6 ms [min:       0.2, max:       0.5, avg:       0.3]
  com.evolveum.midpoint.model.impl.lens.projector.focus.AssignmentTripleEvaluator.evaluateAssignment:                              count:   1000, total time:      1154.9 ms [min:       0.7, max:      11.6, avg:       1.2]
  com.evolveum.midpoint.model.impl.lens.projector.focus.PruningOperation.execute:                                                  count:   1000, total time:         1.6 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.lens.projector.focus.consolidation.DeltaSetTripleMapConsolidation.consolidate:                  count:   1000, total time:      2089.6 ms [min:       1.3, max:      14.1, avg:       2.1]
  com.evolveum.midpoint.model.impl.lens.projector.policy.PolicyRuleEnforcer.execute:                                               count:    500, total time:         3.7 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.model.impl.sync.RecomputeTaskHandler.handle:                                                               count:    500, total time:    169256.4 ms [min:     240.0, max:     589.7, avg:     338.5]
  com.evolveum.midpoint.notifications.impl.NotificationHook.invoke:                                                                count:   2000, total time:         1.9 ms [min:       0.0, max:       0.0, avg:       0.0]
  com.evolveum.midpoint.provisioning.api.ProvisioningService.getObject:                                                            count:  42000, total time:     49697.4 ms [min:       0.0, max:      60.9, avg:       1.2]
  com.evolveum.midpoint.provisioning.impl.AccessChecker.accessCheck:                                                               count:  21000, total time:       228.8 ms [min:       0.0, max:       0.4, avg:       0.0]
  com.evolveum.midpoint.provisioning.impl.ProvisioningContext.getConnectorInstance:                                                count:  21000, total time:       419.5 ms [min:       0.0, max:       0.1, avg:       0.0]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.fetchObject:                                                        count:  21000, total time:      8289.1 ms [min:       0.1, max:      42.8, avg:       0.4]
  com.evolveum.midpoint.provisioning.ucf.api.ConnectorInstance.search:                                                             count:  20000, total time:     19653.9 ms [min:       0.2, max:      59.5, avg:       1.0]
  com.evolveum.midpoint.provisioning.ucf.impl.connid.ConnIdConvertor.convertToResourceObject:                                      count:  21000, total time:       947.7 ms [min:       0.0, max:      25.3, avg:       0.0]
  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:   4003, total time:     14865.1 ms [min:       0.4, max:      67.5, avg:       3.7]
  com.evolveum.midpoint.repo.api.RepositoryService.getVersion:                                                                     count:      1, total time:         0.5 ms [min:       0.5, max:       0.5, avg:       0.5]
  com.evolveum.midpoint.repo.api.RepositoryService.modifyObject:                                                                   count:     17, total time:       337.8 ms [min:       4.8, max:      38.9, avg:      19.9]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjects:                                                                  count:   1511, total time:     17350.0 ms [min:       0.6, max:    1157.5, avg:      11.5]
  com.evolveum.midpoint.repo.api.RepositoryService.searchObjectsIterative:                                                         count:      1, total time:     30109.5 ms [min:   30109.5, max:   30109.5, avg:   30109.5]
  com.evolveum.midpoint.repo.cache.RepositoryCache.countObjects:                                                                   count:      1, total time:         9.5 ms [min:       9.5, max:       9.5, avg:       9.5]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getObject:                                                                      count:  37001, total time:     18980.9 ms [min:       0.0, max:      67.5, avg:       0.5]
  com.evolveum.midpoint.repo.cache.RepositoryCache.getVersion:                                                                     count:  84029, total time:       299.4 ms [min:       0.0, max:       0.5, avg:       0.0]
  com.evolveum.midpoint.repo.cache.RepositoryCache.searchObjectsIterative:                                                         count:      1, total time:     30110.2 ms [min:   30110.2, max:   30110.2, avg:   30110.2]
  com.evolveum.midpoint.task.api.Task.getPathToRootTask:                                                                           count:   1500, total time:      7771.6 ms [min:       1.2, max:      26.6, avg:       5.2]
  com.evolveum.midpoint.task.api.TaskManager.createTaskInstance:                                                                   count:   1504, total time:      1005.8 ms [min:       0.4, max:      21.2, avg:       0.7]
  com.evolveum.midpoint.task.api.TaskManager.getTaskByIdentifier:                                                                  count:   1500, total time:      7762.5 ms [min:       1.1, max:      26.6, avg:       5.2]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskPlain:                                                        count:      4, total time:        20.4 ms [min:       1.4, max:      13.3, avg:       5.1]
  com.evolveum.midpoint.task.quartzimpl.TaskManagerQuartzImpl.getTaskTypeByIdentifier:                                             count:   1500, total time:      6745.9 ms [min:       0.7, max:      25.9, avg:       4.5]
  com.evolveum.midpoint.wf.impl.hook.WfHook.invoke:                                                                                count:   2000, total time:        25.2 ms [min:       0.0, max:       1.3, avg:       0.0]
  com.evolveum.midpoint.wf.impl.processors.primary.PrimaryChangeProcessor.previewOrProcessModelInvocation:                         count:    500, total time:         1.0 ms [min:       0.0, max:       0.1, avg:       0.0]
  interface com.evolveum.midpoint.notifications.api.NotificationManager.processEvent:                                              count:      1, total time:         6.1 ms [min:       6.1, max:       6.1, avg:       6.1]
  org.identityconnectors.framework.api.ConnectorFacade.getObject:                                                                  count:  21000, total time:      4581.6 ms [min:       0.1, max:      40.3, avg:       0.2]
  org.identityconnectors.framework.api.ConnectorFacade.search:                                                                     count:  20000, total time:     16060.0 ms [min:       0.1, max:      59.3, avg:       0.8]



