On 20/05/15 07:31, Fraser Tweedale wrote:
I am experiencing ipa-server-install failure which seems to be
caused by IPAUpgrade.__start_nowait() (upgradeinstance.py:174). It
is claimed that the LDAP connection will wait for the (Unix) socket
but it does not - instead it fails to connect. Did something chance
recently that would cause the connction to not wait for the socket?
For now I am working around it with a short sleep in __start_nowait.
ipaserver-install.log sample beneath.
Cheers,
Fraser
2015-05-20T03:50:37Z DEBUG Upgrading IPA:
2015-05-20T03:50:37Z DEBUG [1/9]: stopping directory server
2015-05-20T03:50:37Z DEBUG Starting external process
2015-05-20T03:50:37Z DEBUG args='/bin/systemctl' 'stop'
'dirsrv@IPA-LOCAL.service'
2015-05-20T03:50:40Z DEBUG Process finished, return code=0
2015-05-20T03:50:40Z DEBUG stdout=
2015-05-20T03:50:40Z DEBUG stderr=
2015-05-20T03:50:40Z DEBUG duration: 3 seconds
2015-05-20T03:50:40Z DEBUG [2/9]: saving configuration
2015-05-20T03:50:40Z DEBUG Loading StateFile from
'/var/lib/ipa/sysrestore/sysrestore.state'
2015-05-20T03:50:40Z DEBUG Saving StateFile to
'/var/lib/ipa/sysrestore/sysrestore.state'
2015-05-20T03:50:40Z DEBUG Loading StateFile from
'/var/lib/ipa/sysrestore/sysrestore.state'
2015-05-20T03:50:40Z DEBUG Saving StateFile to
'/var/lib/ipa/sysrestore/sysrestore.state'
2015-05-20T03:50:40Z DEBUG duration: 0 seconds
2015-05-20T03:50:40Z DEBUG [3/9]: disabling listeners
2015-05-20T03:50:40Z DEBUG duration: 0 seconds
2015-05-20T03:50:40Z DEBUG [4/9]: enabling DS global lock
2015-05-20T03:50:40Z DEBUG duration: 0 seconds
2015-05-20T03:50:40Z DEBUG [5/9]: starting directory server
2015-05-20T03:50:40Z DEBUG Starting external process
2015-05-20T03:50:40Z DEBUG args='/bin/systemctl' 'start'
'dirsrv@IPA-LOCAL.service'
2015-05-20T03:50:40Z DEBUG Process finished, return code=0
2015-05-20T03:50:40Z DEBUG stdout=
2015-05-20T03:50:40Z DEBUG stderr=
2015-05-20T03:50:40Z DEBUG duration: 0 seconds
2015-05-20T03:50:40Z DEBUG [6/9]: upgrading server
2015-05-20T03:50:41Z DEBUG importing all plugin modules in
'/usr/lib/python2.7/site-packages/ipalib/plugins'...
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/aci.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/automember.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/automount.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/baseldap.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/baseuser.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/batch.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/cert.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/config.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/delegation.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/dns.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/group.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/hbacrule.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/hbacsvc.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/hbacsvcgroup.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/hbactest.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/host.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/hostgroup.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/idrange.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/idviews.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/internal.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/kerberos.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/krbtpolicy.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/migration.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/misc.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/netgroup.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/otpconfig.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/otptoken.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/otptoken_yubikey.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/passwd.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/permission.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/ping.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/pkinit.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/privilege.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/pwpolicy.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/radiusproxy.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/realmdomains.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/role.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/rpcclient.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/selfservice.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/selinuxusermap.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/service.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/stageuser.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/sudocmd.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/sudocmdgroup.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/sudorule.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/trust.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/user.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipalib/plugins/virtual.py'
2015-05-20T03:50:41Z DEBUG importing all plugin modules in
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins'...
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/adtrust.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/ca_renewal_master.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/dns.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/fix_replica_agreements.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/rename_managed.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/update_idranges.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/update_managed_permissions.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/update_pacs.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/update_passsync.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/update_referint.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/update_services.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/update_uniqueness.py'
2015-05-20T03:50:41Z DEBUG importing plugin module
'/usr/lib/python2.7/site-packages/ipaserver/install/plugins/upload_cacrt.py'
2015-05-20T03:50:41Z ERROR Upgrade failed with cannot connect to
'ldapi://%2fvar%2frun%2fslapd-IPA-LOCAL.socket':
2015-05-20T03:50:41Z DEBUG Traceback (most recent call last):
File
"/usr/lib/python2.7/site-packages/ipaserver/install/upgradeinstance.py", line
304, in __upgrade
ld = ldapupdate.LDAPUpdate(dm_password='', ldapi=True)
File "/usr/lib/python2.7/site-packages/ipaserver/install/ldapupdate.py",
line 314, in __init__
self.create_connection()
File "/usr/lib/python2.7/site-packages/ipaserver/install/ldapupdate.py",
line 862, in create_connection
autobind=self.ldapi)
File "/usr/lib/python2.7/site-packages/ipalib/backend.py", line 66, in
connect
conn = self.create_connection(*args, **kw)
File "/usr/lib/python2.7/site-packages/ipaserver/plugins/ldap2.py", line
188, in create_connection
client_controls=clientctrls)
File "/usr/lib/python2.7/site-packages/ipapython/ipaldap.py", line 1074, in
external_bind
'', auth_tokens, server_controls, client_controls)
File "/usr/lib64/python2.7/contextlib.py", line 35, in __exit__
self.gen.throw(type, value, traceback)
File "/usr/lib/python2.7/site-packages/ipapython/ipaldap.py", line 976, in
error_handler
error=info)
NetworkError: cannot connect to 'ldapi://%2fvar%2frun%2fslapd-IPA-LOCAL.socket':
2015-05-20T03:50:41Z DEBUG Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/ipaserver/install/service.py", line
388, in start_creation
run_step(full_msg, method)
File "/usr/lib/python2.7/site-packages/ipaserver/install/service.py", line
378, in run_step
method()
File
"/usr/lib/python2.7/site-packages/ipaserver/install/upgradeinstance.py", line
315, in __upgrade
raise RuntimeError(e)
RuntimeError: cannot connect to 'ldapi://%2fvar%2frun%2fslapd-IPA-LOCAL.socket':
2015-05-20T03:50:41Z DEBUG [error] RuntimeError: cannot connect to
'ldapi://%2fvar%2frun%2fslapd-IPA-LOCAL.socket':
2015-05-20T03:50:41Z DEBUG [cleanup]: stopping directory server
2015-05-20T03:50:41Z DEBUG Starting external process
2015-05-20T03:50:41Z DEBUG args='/bin/systemctl' 'stop'
'dirsrv@IPA-LOCAL.service'
2015-05-20T03:50:41Z DEBUG Process finished, return code=0
2015-05-20T03:50:41Z DEBUG stdout=
2015-05-20T03:50:41Z DEBUG stderr=
2015-05-20T03:50:41Z DEBUG duration: 0 seconds
2015-05-20T03:50:41Z DEBUG [cleanup]: restoring configuration
2015-05-20T03:50:41Z DEBUG Loading StateFile from
'/var/lib/ipa/sysrestore/sysrestore.state'
2015-05-20T03:50:41Z DEBUG Loading StateFile from
'/var/lib/ipa/sysrestore/sysrestore.state'
2015-05-20T03:50:41Z DEBUG Saving StateFile to
'/var/lib/ipa/sysrestore/sysrestore.state'
2015-05-20T03:50:41Z DEBUG Loading StateFile from
'/var/lib/ipa/sysrestore/sysrestore.state'
2015-05-20T03:50:41Z DEBUG Loading StateFile from
'/var/lib/ipa/sysrestore/sysrestore.state'
2015-05-20T03:50:41Z DEBUG Saving StateFile to
'/var/lib/ipa/sysrestore/sysrestore.state'
2015-05-20T03:50:41Z DEBUG Loading StateFile from
'/var/lib/ipa/sysrestore/sysrestore.state'
2015-05-20T03:50:41Z DEBUG duration: 0 seconds
2015-05-20T03:50:41Z DEBUG File
"/usr/lib/python2.7/site-packages/ipaserver/install/installutils.py", line 733,
in run_script
return_value = main_function()
File "/sbin/ipa-server-install", line 1263, in main
ds.apply_updates()
File "/usr/lib/python2.7/site-packages/ipaserver/install/dsinstance.py",
line 513, in apply_updates
raise RuntimeError("Update failed: %s" % e)
2015-05-20T03:50:41Z DEBUG The ipa-server-install command failed, exception:
RuntimeError: Update failed: cannot connect to
'ldapi://%2fvar%2frun%2fslapd-IPA-LOCAL.socket':
Thank you for this, I'm not able to reproduce this, but I will dig into
and try to find where the issue is.
Martin^2
--
Martin Basti
--
Manage your subscription for the Freeipa-devel mailing list:
https://www.redhat.com/mailman/listinfo/freeipa-devel
Contribute to FreeIPA: http://www.freeipa.org/page/Contribute/Code
