RHEL Engineering is moving the tracking of its product development work on RHEL 6 through RHEL 9 to Red Hat Jira (issues.redhat.com). If you're a Red Hat customer, please continue to file support cases via the Red Hat customer portal. If you're not, please head to the "RHEL project" in Red Hat Jira and file new tickets here. Individual Bugzilla bugs in the statuses "NEW", "ASSIGNED", and "POST" are being migrated throughout September 2023. Bugs of Red Hat partners with an assigned Engineering Partner Manager (EPM) are migrated in late September as per pre-agreed dates. Bugs against components "kernel", "kernel-rt", and "kpatch" are only migrated if still in "NEW" or "ASSIGNED". If you cannot log in to RH Jira, please consult article #7032570. That failing, please send an e-mail to the RH Jira admins at rh-issues@redhat.com to troubleshoot your issue as a user management inquiry. The email creates a ServiceNow ticket with Red Hat. Individual Bugzilla bugs that are migrated will be moved to status "CLOSED", resolution "MIGRATED", and set with "MigratedToJIRA" in "Keywords". The link to the successor Jira issue will be found under "Links", have a little "two-footprint" icon next to it, and direct you to the "RHEL project" in Red Hat Jira (issue links are of type "https://issues.redhat.com/browse/RHEL-XXXX", where "X" is a digit). This same link will be available in a blue banner at the top of the page informing you that that bug has been migrated.
Bug 1950914 - ipa-otpd fails with "Input/output error: IO error received on bind socket"
Summary: ipa-otpd fails with "Input/output error: IO error received on bind socket"
Keywords:
Status: CLOSED ERRATA
Alias: None
Product: Red Hat Enterprise Linux 8
Classification: Red Hat
Component: ipa
Version: 8.4
Hardware: Unspecified
OS: Unspecified
high
high
Target Milestone: beta
: ---
Assignee: Thomas Woerner
QA Contact: ipa-qe
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2021-04-19 07:01 UTC by Thorsten Scherf
Modified: 2021-11-09 23:07 UTC (History)
10 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed: 2021-11-09 18:22:22 UTC
Type: ---
Target Upstream Version:
Embargoed:

Attachments (Terms of Use)


Links
System ID Private Priority Status Summary Last Updated
Red Hat Issue Tracker FREEIPA-7277 0 None None None 2021-11-09 18:27:35 UTC
Red Hat Product Errata RHBA-2021:4230 0 None None None 2021-11-09 18:22:36 UTC

Description Thorsten Scherf 2021-04-19 07:01:05 UTC 
This bug is created as a clone of upstream ticket:
https://pagure.io/freeipa/issue/6587

Today I've received new ipa packages via CentOS.
After the installation IPA was restarted successfully, but
"systemctl is-system-running" reports:


     jochen@freeipa1:~$ systemctl is-system-running
     degraded


When I look for failed services I see ipa-otpd:


    jochen@freeipa1:~$ systemctl list-units | grep failed
    ● ipa-otpd   loaded failed failed    ipa-otpd service
    jochen@freeipa1:~$ systemctl list-units | grep ipa-otpd
    ● ipa-otpd   loaded failed failed    ipa-otpd service
      ipa-otpd  loaded active running   ipa-otpd service (PID 28372/UID 0)
      ipa-otpd.socket             loaded active listening ipa-otpd socket


It would be nice to have no "failed" services.
Comment 17 Rob Crittenden 2021-07-22 17:52:19 UTC 
For verification follow the the steps from lukasjuhrich in the upstream issue https://pagure.io/freeipa/issue/6587


- reset all failed units (I did killall ipa-otpd). Be sure none are running.
- setting nsslapd-idletimeout to 60 (or less perhaps)
- systemctl restart dirsrv.target
- kinit -T $ARMOR someuser
- wait 60s (or whatever)

To change nsslapd-idletimeout:

ldapmodify -x -D 'cn=directory manager' -W

dn: cn=config
changetype: modify
replace: nsslapd-idletimeout:
nsslapd-idletimeout: 60
<empty>
^D

In the report the ipa-otpd process ends with:

Mar 31 21:28:58 myhost ipa-otpd[2322932]: lukasjuhrich: response sent: Access-Accept
Mar 31 21:29:58 myhost ipa-otpd[2322932]:     bind.c:088: Input/output error: IO error received on bind socket
Mar 31 21:29:58 myhost systemd[1]: ipa-otpd: Main process exited, code=exited, status=1/FAILURE
Mar 31 21:29:58 myhost systemd[1]: ipa-otpd: Failed with result 'exit-code'.

With this change the process ends with:

Jul 22 13:42:03 ipa.example.test ipa-otpd[1800810]: tuser1: response sent: Access-Accept
Jul 22 13:43:33 ipa.example.test ipa-otpd[1800810]:     bind.c:088: Input/output error: IO error received on bind socket: Can't contact LDAP server
Jul 22 13:43:33 ipa.example.test systemd[1]: ipa-otpd: Succeeded.

So same error message, different result value.

You'll notice that in the reporter's testing the process ended exactly 60s after the auth success. In my test it was 90s. I can't account for that.
Comment 18 anuja 2021-07-28 12:30:25 UTC 
Verified using :

2021-07-28T11:39:33+0000 ok: [master.ipa.test] => (item=ipa-server) =>
2021-07-28T11:39:33+0000   msg:
2021-07-28T11:39:33+0000   - arch: x86_64
2021-07-28T11:39:33+0000     epoch: null
2021-07-28T11:39:33+0000     name: ipa-server
2021-07-28T11:39:33+0000     release: 1.module+el8.5.0+11410+91a33fe4
2021-07-28T11:39:33+0000     source: rpm
2021-07-28T11:39:33+0000     version: 4.9.5

2021-07-28T11:52:54+0000 ============================= test session starts ==============================
...
...
2021-07-28T12:03:56+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_otp_auth_ind PASSED [ 14%]
2021-07-28T12:04:27+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_hopt PASSED    [ 28%]
2021-07-28T12:04:47+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_totp PASSED    [ 42%]
2021-07-28T12:05:13+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_otptoken_sync PASSED [ 57%]
2021-07-28T12:06:13+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_2fa_enable_single_prompt PASSED [ 71%]
2021-07-28T12:07:11+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_2fa_disable_single_prompt PASSED [ 85%]
2021-07-28T12:09:51+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_check_otpd_after_idle_timeout PASSED [100%]
2021-07-28T12:09:51+0000
2021-07-28T12:09:51+0000 ------------------ generated xml file: /tmp/wp/twd/junit.xml -------------------
2021-07-28T12:09:51+0000 ------------- generated html file: file:///tmp/wp/twd/report.html --------------
2021-07-28T12:09:51+0000 ========================= 7 passed in 1022.19 seconds ==========================
Comment 19 anuja 2021-07-30 07:51:21 UTC 
Verified using nightly compose:

(A) runner.log 
2021-07-30T07:15:30+0000 TASK [List installed IPA packages version] *************************************
2021-07-30T07:15:30+0000 ok: [master.ipa.test] => (item=ipa-server) => 
2021-07-30T07:15:30+0000   msg:
2021-07-30T07:15:30+0000   - arch: x86_64
2021-07-30T07:15:30+0000     epoch: null
2021-07-30T07:15:30+0000     name: ipa-server
2021-07-30T07:15:30+0000     release: 4.module+el8.5.0+11912+1b4496cf
2021-07-30T07:15:30+0000     source: rpm
2021-07-30T07:15:30+0000     version: 4.9.6

(B) test-result.txt.gz

test_check_otpd_after_idle_timeout from test_integration/test_otp.py

2021-07-30T07:18:34+0000 ============================= test session starts ==============================
2021-07-30T07:18:34+0000 platform linux -- Python 3.6.8, pytest-3.10.1, py-1.10.0, pluggy-0.13.1 -- /usr/bin/python3
2021-07-30T07:18:34+0000 cachedir: .pytest_cache
2021-07-30T07:18:34+0000 metadata: {'Python': '3.6.8', 'Platform': 'Linux-4.18.0-324.el8.x86_64-x86_64-with-redhat-8.5-Ootpa', 'Packages': {'pytest': '3.10.1', 'py': '1.10.0', 'pluggy': '0.13.1'}, 'Plugins': {'metadata': '1.11.0', 'html': '1.22.1', 'multihost': '3.0', 'sourceorder': '0.5'}}
2021-07-30T07:18:34+0000 rootdir: /tmp/wp/freeipa, inifile: tox.ini
2021-07-30T07:18:34+0000 plugins: metadata-1.11.0, html-1.22.1, multihost-3.0, sourceorder-0.5
2021-07-30T07:18:34+0000 collecting ... collected 7 items
2021-07-30T07:18:34+0000 
2021-07-30T07:27:32+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_otp_auth_ind PASSED [ 14%]
2021-07-30T07:27:47+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_hopt PASSED    [ 28%]
2021-07-30T07:27:59+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_totp PASSED    [ 42%]
2021-07-30T07:28:14+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_otptoken_sync PASSED [ 57%]
2021-07-30T07:28:50+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_2fa_enable_single_prompt PASSED [ 71%]
2021-07-30T07:29:28+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_2fa_disable_single_prompt PASSED [ 85%]
2021-07-30T07:31:41+0000 ipatests/test_integration/test_otp.py::TestOTPToken::test_check_otpd_after_idle_timeout PASSED [100%]
2021-07-30T07:31:41+0000 
2021-07-30T07:31:41+0000 ------------------ generated xml file: /tmp/wp/twd/junit.xml -------------------
2021-07-30T07:31:41+0000 ------------- generated html file: file:///tmp/wp/twd/report.html --------------
2021-07-30T07:31:41+0000 =============================== warnings summary ===============================
Comment 22 errata-xmlrpc 2021-11-09 18:22:22 UTC 
Since the problem described in this bug report should be
resolved in a recent advisory, it has been closed with a
resolution of ERRATA.

For information on the advisory (ipa bug fix and enhancement update), and where to find the updated
files, follow the link below.

If the solution does not work for you, open a new bug report.

https://access.redhat.com/errata/RHBA-2021:4230
Note You need to log in before you can comment on or make changes to this bug.