[Freeipa-users] migrate-ds aborts

Fri Jan 16 15:48:34 UTC 2015

Thanks for looking into this!

I was finally able to import all 11811 user records into IPA, but even now, when I re-run the migrate, I get the same failure.

I enabled debug in the default.cfg, and this is the tail of the httpd error_log:

.
.
.
 [Fri Jan 16 09:28:29.046991 2015] [:error] [pid 14924] ipa: WARNING: GID number 11 of migrated user andy does not point to a known group.
[Fri Jan 16 09:28:29.051353 2015] [:error] [pid 14924] ipa: INFO: admin at IDMTEST.EXAMPLE.COM: migrate_ds(u'ldap://10.x.x.x:389', u'********', binddn=u'uid=me,ou=people,ou=agroup,dc=example,dc=com', usercontainer=u'ou=people', groupcontainer=u'ou=groups', userobjectclass=(u'person',), groupobjectclass=(u'groupOfUniqueNames', u'groupOfNames'), userignoreobjectclass=None, userignoreattribute=None, groupignoreobjectclass=None, groupignoreattribute=None, groupoverwritegid=False, schema=u'RFC2307bis', continue=True, basedn=u'ou=agroup,dc=example,dc=com', compat=False, version=u'2.65', exclude_groups=None, exclude_users=None): NetworkError
[Fri Jan 16 09:28:29.051428 2015] [:error] [pid 14924] ipa: DEBUG: response: NetworkError: cannot connect to 'ldap://10.x.x.x:389':
[Fri Jan 16 09:28:29.054057 2015] [:error] [pid 14924] ipa: DEBUG: no session id in request, generating empty session data with id=c0d2c8b3803593b30684e15ff1f57e0e
[Fri Jan 16 09:28:29.054173 2015] [:error] [pid 14924] ipa: DEBUG: store session: session_id=c0d2c8b3803593b30684e15ff1f57e0e start_timestamp=2015-01-16T09:28:29 access_timestamp=2015-01-16T09:28:29 expiration_timestamp=1969-12-31T18:00:00
[Fri Jan 16 09:28:29.054395 2015] [:error] [pid 14924] ipa: DEBUG: finalize_kerberos_acquisition: xmlserver ccache_name="FILE:/run/httpd/krbcache/krb5cc_apache_zTGsku" session_id="c0d2c8b3803593b30684e15ff1f57e0e"
[Fri Jan 16 09:28:29.054463 2015] [:error] [pid 14924] ipa: DEBUG: reading ccache data from file "/run/httpd/krbcache/krb5cc_apache_zTGsku"
[Fri Jan 16 09:28:29.054851 2015] [:error] [pid 14924] ipa: DEBUG: get_credential_times: principal=HTTP/myipatestserver.example.com at IDMTEST.EXAMPLE.COM, authtime=01/15/15 16:44:10, starttime=01/15/15 16:44:17, endtime=01/16/15 16:44:04, renew_till=12/31/69 18:00:00
[Fri Jan 16 09:28:29.055014 2015] [:error] [pid 14924] ipa: DEBUG: KRB5_CCache FILE:/run/httpd/krbcache/krb5cc_apache_zTGsku endtime=1421448244 (01/16/15 16:44:04)
[Fri Jan 16 09:28:29.055109 2015] [:error] [pid 14924] ipa: DEBUG: set_session_expiration_time: duration_type=inactivity_timeout duration=1200 max_age=1421447944 expiration=1421423309.06 (2015-01-16T09:48:29)
[Fri Jan 16 09:28:29.055217 2015] [:error] [pid 14924] ipa: DEBUG: store session: session_id=c0d2c8b3803593b30684e15ff1f57e0e start_timestamp=2015-01-16T09:28:29 access_timestamp=2015-01-16T09:28:29 expiration_timestamp=2015-01-16T09:48:29
[Fri Jan 16 09:28:29.055806 2015] [:error] [pid 14924] ipa: DEBUG: Destroyed connection context.ldap2_140392345753040
[Fri Jan 16 09:28:29.056471 2015] [:error] [pid 14924] ipa: DEBUG: Destroyed connection context.ldap2

One thing that is also confusing me, is that I am getting this error:
[Fri Jan 16 09:28:29.007575 2015] [:error] [pid 14924] ipa: WARNING: GID number 11 of migrated user anyone does not point to a known group.

And it never migrates my groups.  The ou=Groups is used in my source openLDAP tree, so I'm not sure why it wouldn't migrate.
Bill
-----Original Message-----
From: Martin Kosek [mailto:mkosek at redhat.com]
Sent: Friday, January 16, 2015 2:25 AM
To: Ludwig Krispenz
Cc: Quayle, Bill; 'freeipa-users at redhat.com'
Subject: Re: [Freeipa-users] migrate-ds aborts

On 01/16/2015 09:14 AM, Ludwig Krispenz wrote:
>
> On 01/16/2015 08:43 AM, Martin Kosek wrote:
>> On 01/15/2015 06:31 PM, Quayle, Bill wrote:
>>> I am migrating an openLDAP tree into ipa, and when I run ipa
>>> migrate-ds, the migration aborts after roughly 36 seconds with:
>>>
>>> ipa: ERROR: cannot connect to 'ldap://10.x.x.x:389':
>>>
>>> It has transferred 9762 records, but seems to hit a timeout that
>>> causes it to stop.
>>>
>>> I've run it in debug mode, which only provides this:
>>>
>>> ipa: DEBUG: Starting external process
>>>
>>> ipa: DEBUG: args=keyctl pupdate 774698354
>>>
>>> ipa: DEBUG: Process finished, return code=0
>>>
>>> ipa: DEBUG: stdout=
>>>
>>> ipa: DEBUG: stderr=
>>>
>>> ipa: DEBUG: Caught fault 907 from server
>>> https://foo.example.com/ipa/session/xml: cannot connect to
>>> 'ldap://10.x.x.x:389':
>>>
>>> ipa: DEBUG: Destroyed connection context.xmlclient
>>>
>>> ipa: ERROR: cannot connect to 'ldap://10.x.x.x:389':
>>>
>>> Initially, it had transferred 2000 records and stopped, until I set
>>> nsslapd-sizelimit in cn=config:
>>>
>>> nsslapd-sizelimit: 20000
>>>
>>> I then re-ran the migration a dozen times, each time it would
>>> transfer more records, but would always time out at around the 36
>>> second mark.  Now that I'm at 9762 records, it seems to have reached a peak.
>>>
>>> I suspect this is another tunable, but haven't been able to find it,
>>> any document that mentions it, or anyone else hitting this issue.
>>>
>>> RHEL 7.0 server
>>>
>>> idM ipa-server-3.3.3-28
>>>
>>> source is RHEL 6.5 running openldap-2.4.23-34
>>>
>>> command used to migrate:
>>>
>>> ipa migrate-ds --continue --bind-dn="uid=me,ou=people,ou=foo,dc=example,dc=com"
>>> --base-dn="ou=foo,dc=example,dc=com" ldap://10.x.x.x:389
>>>
>>> *Cheers,*
>>>
>>> *-Bill*
>>
>> Ludwig, do you know? I am just thinking it may be also caused by some
>> form of timelimit, as mentioned in
>>
>> https://access.redhat.com/documentation/en-US/Red_Hat_Directory_Serve
>> r/8.2/html/Administration_Guide/User_Account_Management-Setting_Resou
>> rce_Limits_Based_on_the_Bind_DN.html
>>
>>
>> (those apply both for bind DNs and global cn=config). Maybe
>> nsslapd-timelimit could be increased? Although I saw the default is
>> 3600, I assume it means 1 hour, i.e. not being the root cause.
> we need the access and error logs from DS, if it is a DS limit it
> should be seen in the err code.

+1

> Could it be that migrate-ds has it's own limit waiting for a repsponse from DS ?

The search itself in migrate-ds is limit-less:

             try:
                 entries, truncated = ds_ldap.find_entries(
                     search_filter, ['*'], search_bases[ldap_obj_name],
                     ds_ldap.SCOPE_ONELEVEL,
                     time_limit=0, size_limit=-1,
                     search_refs=True    # migrated DS may contain search
references
                 )
              except...

Bill, I am wondering, could you add debug=True to /etc/ipa/default.conf on your server, reload the httpd process and re-run the migration? It should print additional debugging information that may help us.

Martin

________________________________

CONFIDENTIALITY AND SECURITY NOTICE

The contents of this message and any attachments may be confidential and proprietary. If you are not an intended recipient, please inform the sender of the transmission error and delete this message immediately without reading, distributing or copying the contents.