Import from resource logs twice EXECUTION to audit

2013-08-01 16:41:29,622 [RESOURCEOBJECTCHANGELISTENER] [midPointScheduler_Worker-5] INFO (com.evolveum.midpoint.audit.log): 2013-08-01T16:41:29.124+0200 eid=1375368089622-0-1, et=ADD_OBJECT, es=REQUEST, sid=null, tid=1375368081011-0-1, toid=02712104-87e6-47ae-acd8-fe6a6a951f67, hid=null, I=ObjectType:00000000-0000-0000-0000-000000000002({http://midpoint.evolveum.com/xml/ns/public/common/common-2a}user), T=ObjectType:fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc({http://midpoint.evolveum.com/xml/ns/public/common/common-2a}UserType), TO=null, D=[], ch=http://midpoint.evolveum.com/xml/ns/public/provisioning/channels-2#import, o=null, p=null, m=null
2013-08-01 16:41:29,623 [RESOURCEOBJECTCHANGELISTENER] [midPointScheduler_Worker-5] DEBUG (com.evolveum.midpoint.audit.log): Details of event 1375368089622-0-1 stage REQUEST
Deltas:
2013-08-01 16:41:29,637 [RESOURCEOBJECTCHANGELISTENER] [midPointScheduler_Worker-5] INFO (com.evolveum.midpoint.audit.log): 2013-08-01T16:41:29.637+0200 eid=1375368089637-0-1, et=ADD_OBJECT, es=EXECUTION, sid=null, tid=1375368081011-0-1, toid=02712104-87e6-47ae-acd8-fe6a6a951f67, hid=null, I=ObjectType:00000000-0000-0000-0000-000000000002({http://midpoint.evolveum.com/xml/ns/public/common/common-2a}user), T=ObjectType:fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc({http://midpoint.evolveum.com/xml/ns/public/common/common-2a}UserType), TO=null, D=[fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc:ADD,fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc:MODIFY], ch=http://midpoint.evolveum.com/xml/ns/public/provisioning/channels-2#import, o=SUCCESS, p=null, m=
2013-08-01 16:41:29,639 [RESOURCEOBJECTCHANGELISTENER] [midPointScheduler_Worker-5] DEBUG (com.evolveum.midpoint.audit.log): Details of event 1375368089637-0-1 stage EXECUTION
Deltas:
  ObjectDeltaOperation
    Delta:
      ObjectDelta<UserType>(oid=fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc,ADD):
        PO: {.../common/common-2a}UserType, fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc def({.../common/common-2a}UserType)
          PCV: null
            PC: {.../common/common-2a}extension def({http://example.com/mycustomer}UserExtensionType,runtime)
              PCV: null
                PP: {http://example.com/mycustomer}employed = [ PPV(Boolean:false, origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({xsd:}boolean[0,1],RCU)
                PP: {http://example.com/mycustomer}department = [ PPV(String:Executive Division, origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({xsd:}string[0,1],RCU)
                PP: {http://example.com/mycustomer}inEvidence = [ PPV(Boolean:false, origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({xsd:}boolean[0,1],RCU)
            PP: {.../common/common-2a}givenName = [ PPV(PolyString:Richard, origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({http://prism.evolveum.com/xml/ns/public/types-2}PolyStringType[0,1],RCU)
            PP: {.../common/common-2a}name = [ PPV(PolyString:X000002, origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({http://prism.evolveum.com/xml/ns/public/types-2}PolyStringType[0,1],RCU)
            PP: {.../common/common-2a}employeeNumber = [ PPV(String:000002, origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({xsd:}string[0,1],RCU)
            PP: {.../common/common-2a}familyName = [ PPV(PolyString:Dwayne, origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({http://prism.evolveum.com/xml/ns/public/types-2}PolyStringType[0,1],RCU)
            PP: {.../common/common-2a}title = [ PPV(PolyString:General Manager, origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({http://prism.evolveum.com/xml/ns/public/types-2}PolyStringType[0,1],RCU)
            PC: {.../common/common-2a}credentials def({.../common/common-2a}CredentialsType)
              PCV: null
                PC: {.../common/common-2a}password def({.../common/common-2a}PasswordType)
                  PCV: null
                    PP: {.../common/common-2a}value = [ PPV(ProtectedStringType:ProtectedStringType([encrypted data]), origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({.../common/common-2a}ProtectedStringType[0,1],RCU)
            PC: {.../common/common-2a}activation def({.../common/common-2a}ActivationType)
              PCV: null
                PP: {.../common/common-2a}administrativeStatus = [ PPV(ActivationStatusType:DISABLED, origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({.../common/common-2a}ActivationStatusType[0,1],RCU)
                PP: {.../common/common-2a}validFrom = [ PPV(XMLGregorianCalendarImpl:2000-05-01T08:00:50.000+02:00, origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({xsd:}dateTime[0,1],RCU)
                PP: {.../common/common-2a}validTo = [ PPV(XMLGregorianCalendarImpl:2012-09-30T17:35:50.000+02:00, origin: INBOUND:resource:10000000-9999-9999-0000-a000ff000001(HR Source)) ] def({xsd:}dateTime[0,1],RCU)
                PP: {.../common/common-2a}validityStatus = [ PPV(TimeIntervalStatusType:AFTER, origin: USER_POLICY:null) ] def({.../common/common-2a}TimeIntervalStatusType[0,1],RCU)
                PP: {.../common/common-2a}validityChangeTimestamp = [ PPV(XMLGregorianCalendarImpl:2013-08-01T16:41:29.124+02:00, origin: USER_POLICY:null) ] def({xsd:}dateTime[0,1],RCU)
                PP: {.../common/common-2a}effectiveStatus = [ PPV(ActivationStatusType:DISABLED, origin: USER_POLICY:null) ] def({.../common/common-2a}ActivationStatusType[0,1],RCU)
                PP: {.../common/common-2a}disableTimestamp = [ PPV(XMLGregorianCalendarImpl:2013-08-01T16:41:29.124+02:00, origin: USER_POLICY:null) ] def({xsd:}dateTime[0,1],RCU)
            PP: {.../common/common-2a}fullName = [ PPV(PolyString:Richard Dwayne, origin: USER_POLICY:objectTemplate:10000000-9999-9999-0000-a000fe000001(Parasol User Template)) ] def({http://prism.evolveum.com/xml/ns/public/types-2}PolyStringType[0,1],RCU)
            PC: {.../common/common-2a}assignment def({.../common/common-2a}AssignmentType)
              PCV: null
                PR: {.../common/common-2a}targetRef = [ PRV(oid=00000000-9999-9999-0000-100000000030, targetType={.../common/common-2a}OrgType),  ] def
            PR: {.../common/common-2a}parentOrgRef = [ PRV(oid=00000000-9999-9999-0000-100000000030, targetType={.../common/common-2a}OrgType),  ] def
            PC: {.../common/common-2a}metadata def({.../common/common-2a}MetadataType)
              PCV: null
                PP: {.../common/common-2a}createChannel = [ PPV(String:http://midpoint.evolveum.com/xml/ns/public/provisioning/channels-2#import) ] def({xsd:}anyURI[0,1],RCU,I)
                PP: {.../common/common-2a}createTimestamp = [ PPV(XMLGregorianCalendarImpl:2013-08-01T16:41:29.353+02:00) ] def({xsd:}dateTime[0,1],RCU,I)
                PR: {.../common/common-2a}creatorRef = [ PRV(oid=00000000-0000-0000-0000-000000000002, targetType={.../common/common-2a}UserType),  ] def
                PR: {.../common/common-2a}createApproverRef = [  ] def
                PR: {.../common/common-2a}modifyApproverRef = [  ] def
            PC: {.../common/common-2a}trigger def({.../common/common-2a}TriggerType)
            PR: {.../common/common-2a}linkRef = [  ] def
            PP: {.../common/common-2a}employeeType = [  ] def({xsd:}string[0,-1],RCU)
            PP: {.../common/common-2a}organizationalUnit = [  ] def({http://prism.evolveum.com/xml/ns/public/types-2}PolyStringType[0,-1],RCU)
            PP: {.../common/common-2a}organization = [  ] def({http://prism.evolveum.com/xml/ns/public/types-2}PolyStringType[0,-1],RCU)
    Execution result:
            *op* com.evolveum.midpoint.model.lens.ChangeExecutor.executeDelta, st: SUCCESS, msg: null
                *op* com.evolveum.midpoint.task.api.Task.listSubtasksDeeply, st: SUCCESS, msg: null
                        [c]oid=02712104-87e6-47ae-acd8-fe6a6a951f67
                        [c]implementationClass=com.evolveum.midpoint.task.quartzimpl.TaskQuartzImpl
                    *op* com.evolveum.midpoint.task.api.Task.listSubtasksRaw, st: SUCCESS, msg: null
                            [c]oid=02712104-87e6-47ae-acd8-fe6a6a951f67
                            [c]implementationClass=com.evolveum.midpoint.task.quartzimpl.TaskQuartzImpl
                        *op* com.evolveum.midpoint.repo.api.RepositoryService.searchObjects, st: SUCCESS, msg: null
                                [p]query=Q{EQUALS: parent, PPV(String:1375368081011-0-1),null paging
                                [p]type=com.evolveum.midpoint.xml.ns._public.common.common_2a.TaskType
                *op* com.evolveum.midpoint.repo.api.RepositoryService.addObject, st: SUCCESS, msg: null
                        [p]object=UserType:fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc(X000002)
                        [p]options=RepoAddOptions(overwrite=false, allowUnencryptedValues=false)

  ObjectDeltaOperation
    Delta:
      ObjectDelta<UserType>(oid=fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc,MODIFY):
        ReferenceDelta( / {.../common/common-2a}linkRef) def
          ADD: PRV(oid=23df4132-92be-41ea-bf2d-9466a0b728c0, targetType={.../common/common-2a}ShadowType)
    Execution result:
            *op* com.evolveum.midpoint.model.lens.ChangeExecutor.linkAccount, st: SUCCESS, msg: null
                *op* com.evolveum.midpoint.repo.api.RepositoryService.modifyObject, st: SUCCESS, msg: null
                        [p]oid=fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc
                        [p]modifications=[ReferenceDelta( / {.../common/common-2a}linkRef, ADD)]
                        [p]type=com.evolveum.midpoint.xml.ns._public.common.common_2a.UserType

2013-08-01 16:41:29,934 [RESOURCEOBJECTCHANGELISTENER] [midPointScheduler_Worker-5] INFO (com.evolveum.midpoint.audit.log): 2013-08-01T16:41:29.934+0200 eid=1375368089934-0-1, et=ADD_OBJECT, es=EXECUTION, sid=null, tid=1375368081011-0-1, toid=02712104-87e6-47ae-acd8-fe6a6a951f67, hid=null, I=ObjectType:00000000-0000-0000-0000-000000000002({http://midpoint.evolveum.com/xml/ns/public/common/common-2a}user), T=ObjectType:fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc({http://midpoint.evolveum.com/xml/ns/public/common/common-2a}UserType), TO=null, D=[fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc:MODIFY], ch=http://midpoint.evolveum.com/xml/ns/public/provisioning/channels-2#import, o=SUCCESS, p=null, m=
2013-08-01 16:41:29,935 [RESOURCEOBJECTCHANGELISTENER] [midPointScheduler_Worker-5] DEBUG (com.evolveum.midpoint.audit.log): Details of event 1375368089934-0-1 stage EXECUTION
Deltas:
  ObjectDeltaOperation
    Delta:
      ObjectDelta<UserType>(oid=fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc,MODIFY):
        PropertyDelta({.../common/common-2a}activation / {.../common/common-2a}validityStatus) def
          REPLACE: PPV(TimeIntervalStatusType:AFTER, origin: USER_POLICY:null)
        PropertyDelta({.../common/common-2a}activation / {.../common/common-2a}validityChangeTimestamp) def
          REPLACE: PPV(XMLGregorianCalendarImpl:2013-08-01T16:41:29.660+02:00, origin: USER_POLICY:null)
        ReferenceDelta( / {.../common/common-2a}parentOrgRef) def
          ADD: PRV(oid=00000000-9999-9999-0000-100000000030, targetType={.../common/common-2a}OrgType)
        PropertyDelta({.../common/common-2a}metadata / {.../common/common-2a}modifyChannel) def
          REPLACE: PPV(String:http://midpoint.evolveum.com/xml/ns/public/provisioning/channels-2#import)
        PropertyDelta({.../common/common-2a}metadata / {.../common/common-2a}modifyTimestamp) def
          REPLACE: PPV(XMLGregorianCalendarImpl:2013-08-01T16:41:29.827+02:00)
        ReferenceDelta({.../common/common-2a}metadata / {.../common/common-2a}modifierRef) def
          REPLACE: PRV(oid=00000000-0000-0000-0000-000000000002, targetType=null)
    Execution result:
            *op* com.evolveum.midpoint.model.lens.ChangeExecutor.executeDelta, st: SUCCESS, msg: null
                *op* com.evolveum.midpoint.task.api.Task.listSubtasksDeeply, st: SUCCESS, msg: null
                        [c]oid=02712104-87e6-47ae-acd8-fe6a6a951f67
                        [c]implementationClass=com.evolveum.midpoint.task.quartzimpl.TaskQuartzImpl
                    *op* com.evolveum.midpoint.task.api.Task.listSubtasksRaw, st: SUCCESS, msg: null
                            [c]oid=02712104-87e6-47ae-acd8-fe6a6a951f67
                            [c]implementationClass=com.evolveum.midpoint.task.quartzimpl.TaskQuartzImpl
                        *op* com.evolveum.midpoint.repo.api.RepositoryService.searchObjects, st: SUCCESS, msg: null
                                [p]query=Q{EQUALS: parent, PPV(String:1375368081011-0-1),null paging
                                [p]type=com.evolveum.midpoint.xml.ns._public.common.common_2a.TaskType
                *op* com.evolveum.midpoint.repo.api.RepositoryService.modifyObject, st: SUCCESS, msg: null
                        [p]oid=fbd99b67-e6c3-4d0f-a728-ccb3b97f45cc
                        [p]modifications=[PropertyDelta({.../common/common-2a}activation / {.../common/common-2a}validityStatus, REPLACE),PropertyDelta({.../common/common-2a}activation / {.../common/common-2a}validityChangeTimestamp, REPLACE),ReferenceDelta( / {.../common/common-2a}parentOrgRef, ADD),PropertyDelta({.../common/common-2a}metadata / {.../common/common-2a}modifyChannel, REPLACE),PropertyDelta({.../common/common-2a}metadata / {.../common/common-2a}modifyTimestamp, REPLACE),ReferenceDelta({.../common/common-2a}metadata / {.../common/common-2a}modifierRef, REPLACE)]
                        [p]type=com.evolveum.midpoint.xml.ns._public.common.common_2a.UserType

2013-08-01 16:41:30,124 [UCF] [midPointScheduler_Worker-5] INFO (com.evolveum.midpoint.model.util.AbstractSearchIterativeResultHandler): Import object object:23df4132-92be-41ea-bf2d-9466a0b728c0(000002) from resource:10000000-9999-9999-0000-a000ff000001(HR Source) done (1043 ms)