#7594 Permission problems on CIS hardened images
Closed: fixed 5 years ago Opened 5 years ago by plastikman.

Issue

CIS hardened images with FIPS 140-2 enabled do not allow Fresh install of FreeIPA 4.5 to work.

Steps to Reproduce

This is less about steps to reproduce, and more about what I needed to do to make freeIPA work on a FIPS 140-2 and CIS-1 2.2.0 level harden

The default umask for the following files is too restrictive and causes kinit -n to fail

[root@example ]# kinit -n
Password for WELLKNOWN/ANONYMOUS@IPA.EXAMPLE.COM:

Kinit was asking for a password instead of trusing the certs

These files in a CIS image are root:root 600, they need to be root:root 644

/var/lib/ipa-client/pki/ca-bundle.pem
/etc/pki/ca-trust/source/ipa.p11-kit

Once the permissions are fixed, you can kinit -n

The web server fails to allow you to log in because the permissions are too restrictive on /etc/httpd/alias, the permissions shuold be 755 but CIS changes them to 600

Checking the error logs, the only detail that was returned was this

ipa: INFO: 401 Unauthorized: [Errno 13] Permission denied

I straced all the apache pids to determine the real error

ps auxw | grep -E 'sbin/(apache|httpd)' | awk '{print"-p " $2}' | xargs strace -e trace=write -F -s 100000000

When entering the username/password the following segfault is returned.

[pid 23361] write(2, "[Thu Jun 21 18:00:54.930103 2018] [:error] [pid 23361] Unable to change directory to /etc/httpd/alias\n", 102) = 102
[pid 23361] write(2, "[Thu Jun 21 18:00:54.930307 2018] [:error] [pid 23361] Does the directory exist and do the permissions allow access?\n", 117) = 117
[pid 23361] --- SIGSEGV {si_signo=SIGSEGV, si_code=SEGV_MAPERR, si_addr=0x28} ---

Once the permissions were solved on /etc/httpd/alias there were still issues, the ipaapi proxy was still returning problems.

The httpd logs still said

ipa: INFO: 401 Unauthorized: [Errno 13] Permission denied

but there were no more segfaults or problems, i traced the ipaapi process

ps auxw | grep -E 'ipaapi' | awk '{print"-p " $2}' | xargs strace -e trace=open -F -s 100000000

after tracing the ipaapi proxy i found that /etc/ipa/ca.crt were also too restrictive (600), they shuold be 644

[pid 25624] open("/etc/ipa/ca.crt", O_RDONLY) = -1 EACCES (Permission denied)

After changing the permissions to 644 everything works.

In summary the following changes needed to be made to make FreeIPA work on a CIS hardened machine.

chmod 644 /var/lib/ipa-client/pki/ca-bundle.pem
chmod 644 /etc/pki/ca-trust/source/ipa.p11-kit
chmod 755 /etc/httpd/alias/
chmod 644 /etc/ipa/ca.crt

Actual behavior

kinit -n does not work, and unable to log into the web UI

Expected behavior

FreeIPA works with CIS restrictive umask settings

Version/Release/Distribution

$ rpm -q freeipa-server freeipa-client ipa-server ipa-client 389-ds-base pki-ca krb5-server

package freeipa-server is not installed
package freeipa-client is not installed
ipa-server-4.5.4-10.el7.centos.1.x86_64
ipa-client-4.5.4-10.el7.centos.1.x86_64
389-ds-base-1.3.7.5-21.el7_5.x86_64
pki-ca-10.5.1-9.el7.noarch
krb5-server-1.15.1-19.el7.x86_64

Additional info:

Any additional information, configuration, data or log snippets that is needed for reproduction or investigation of the issue.

krb5kdc.log

Jun 21 16:07:55 example.ipa.EXAMPLE.com krb5kdc[5044](info): AS_REQ (8 etypes {18 17 20 19 16 23 25 26}) 172.31.28.149: NEEDED_PREAUTH: WELLKNOWN/ANONYMOUS@IPA.EXAMPLE.COM for krbtgt/IPA.EXAMPLE.COM@IPA.EXAMPLE.COM, Additional pre-authentication required
Jun 21 16:07:55 example.ipa.EXAMPLE.com krb5kdc[5044](info): closing down fd 13
Jun 21 16:07:55 example.ipa.EXAMPLE.com krb5kdc[5044](info): AS_REQ (8 etypes {18 17 20 19 16 23 25 26}) 172.31.28.149: ISSUE: authtime 1529597275, etypes {rep=18 tkt=18 ses=18}, WELLKNOWN/ANONYMOUS@IPA.EXAMPLE.COM for krbtgt/IPA.EXAMPLE.COM@IPA.EXAMPLE.COM
Jun 21 16:07:55 example.ipa.EXAMPLE.com krb5kdc[5044](info): closing down fd 13
Jun 21 16:07:55 example.ipa.EXAMPLE.com krb5kdc[5043](info): AS_REQ (8 etypes {18 17 20 19 16 23 25 26}) 172.31.28.149: NEEDED_PREAUTH: plastikman@IPA.EXAMPLE.COM for krbtgt/IPA.EXAMPLE.COM@IPA.EXAMPLE.COM, Additional pre-authentication required
Jun 21 16:07:55 example.ipa.EXAMPLE.com krb5kdc[5043](info): closing down fd 13
Jun 21 16:07:55 example.ipa.EXAMPLE.com krb5kdc[5044](info): AS_REQ (8 etypes {18 17 20 19 16 23 25 26}) 172.31.28.149: ISSUE: authtime 1529597275, etypes {rep=18 tkt=18 ses=18}, plastikman@IPA.EXAMPLE.COM for krbtgt/IPA.EXAMPLE.COM@IPA.EXAMPLE.COM
Jun 21 16:07:55 example.ipa.EXAMPLE.com krb5kdc[5044](info): closing down fd 13
Jun 21 16:07:56 example.ipa.EXAMPLE.com krb5kdc[5043](info): TGS_REQ (8 etypes {18 17 20 19 16 23 25 26}) 172.31.28.149: ISSUE: authtime 1529597275, etypes {rep=18 tkt=18 ses=18}, plastikman@IPA.EXAMPLE.COM for HTTP/example.ipa.EXAMPLE.com@IPA.EXAMPLE.COM
Jun 21 16:07:56 example.ipa.EXAMPLE.com krb5kdc[5043](info): closing down fd 13

error_log

[Thu Jun 21 16:07:41.343047 2018] [core:info] [pid 5075] AH00096: removed PID file /run/httpd/httpd.pid (pid=5075)
[Thu Jun 21 16:07:41.343094 2018] [mpm_prefork:notice] [pid 5075] AH00170: caught SIGWINCH, shutting down gracefully
[Thu Jun 21 16:07:41.343200 2018] [:info] [pid 5528] mod_wsgi (pid=5528): Destroying interpreters.
[Thu Jun 21 16:07:41.343205 2018] [:info] [pid 5085] mod_wsgi (pid=5085): Destroying interpreters.
[Thu Jun 21 16:07:41.343212 2018] [:info] [pid 5528] mod_wsgi (pid=5528): Cleanup interpreter ''.
[Thu Jun 21 16:07:41.343220 2018] [:info] [pid 5085] mod_wsgi (pid=5085): Cleanup interpreter ''.
[Thu Jun 21 16:07:41.344230 2018] [:info] [pid 5087] mod_wsgi (pid=5087): Destroying interpreters.
[Thu Jun 21 16:07:41.344246 2018] [:info] [pid 5087] mod_wsgi (pid=5087): Cleanup interpreter ''.
[Thu Jun 21 16:07:41.346558 2018] [:info] [pid 5528] mod_wsgi (pid=5528): Terminating Python.
[Thu Jun 21 16:07:41.347230 2018] [:info] [pid 5087] mod_wsgi (pid=5087): Terminating Python.
[Thu Jun 21 16:07:41.348152 2018] [:info] [pid 5528] mod_wsgi (pid=5528): Python has shutdown.
[Thu Jun 21 16:07:41.348834 2018] [:info] [pid 5087] mod_wsgi (pid=5087): Python has shutdown.
[Thu Jun 21 16:07:41.354192 2018] [:info] [pid 5088] mod_wsgi (pid=5088): Destroying interpreters.
[Thu Jun 21 16:07:41.354212 2018] [:info] [pid 5088] mod_wsgi (pid=5088): Cleanup interpreter ''.
[Thu Jun 21 16:07:41.354224 2018] [:info] [pid 5089] mod_wsgi (pid=5089): Destroying interpreters.
[Thu Jun 21 16:07:41.354238 2018] [:info] [pid 5089] mod_wsgi (pid=5089): Cleanup interpreter ''.
[Thu Jun 21 16:07:41.357193 2018] [:info] [pid 5089] mod_wsgi (pid=5089): Terminating Python.
[Thu Jun 21 16:07:41.358783 2018] [:info] [pid 5085] mod_wsgi (pid=5085): Terminating Python.
[Thu Jun 21 16:07:41.358804 2018] [:info] [pid 5089] mod_wsgi (pid=5089): Python has shutdown.
[Thu Jun 21 16:07:41.360445 2018] [:info] [pid 5085] mod_wsgi (pid=5085): Python has shutdown.
[Thu Jun 21 16:07:41.364186 2018] [:info] [pid 5523] mod_wsgi (pid=5523): Destroying interpreters.
[Thu Jun 21 16:07:41.364204 2018] [:info] [pid 5523] mod_wsgi (pid=5523): Cleanup interpreter ''.
[Thu Jun 21 16:07:41.366349 2018] [:info] [pid 5088] mod_wsgi (pid=5088): Terminating Python.
[Thu Jun 21 16:07:41.367199 2018] [:info] [pid 5523] mod_wsgi (pid=5523): Terminating Python.
[Thu Jun 21 16:07:41.368038 2018] [:info] [pid 5088] mod_wsgi (pid=5088): Python has shutdown.
[Thu Jun 21 16:07:41.368795 2018] [:info] [pid 5523] mod_wsgi (pid=5523): Python has shutdown.
[Thu Jun 21 16:07:41.374320 2018] [:info] [pid 5527] mod_wsgi (pid=5527): Destroying interpreters.
[Thu Jun 21 16:07:41.374339 2018] [:info] [pid 5527] mod_wsgi (pid=5527): Cleanup interpreter ''.
[Thu Jun 21 16:07:41.377379 2018] [:info] [pid 5527] mod_wsgi (pid=5527): Terminating Python.
[Thu Jun 21 16:07:41.378966 2018] [:info] [pid 5527] mod_wsgi (pid=5527): Python has shutdown.
[Thu Jun 21 16:07:41.384352 2018] [:info] [pid 5086] mod_wsgi (pid=5086): Destroying interpreters.
[Thu Jun 21 16:07:41.384383 2018] [:info] [pid 5086] mod_wsgi (pid=5086): Cleanup interpreter ''.
[Thu Jun 21 16:07:41.384393 2018] [:info] [pid 5518] mod_wsgi (pid=5518): Destroying interpreters.
[Thu Jun 21 16:07:41.384407 2018] [:info] [pid 5518] mod_wsgi (pid=5518): Cleanup interpreter ''.
[Thu Jun 21 16:07:41.387336 2018] [:info] [pid 5086] mod_wsgi (pid=5086): Terminating Python.
[Thu Jun 21 16:07:41.387374 2018] [:info] [pid 5518] mod_wsgi (pid=5518): Terminating Python.
[Thu Jun 21 16:07:41.388946 2018] [:info] [pid 5086] mod_wsgi (pid=5086): Python has shutdown.
[Thu Jun 21 16:07:41.388983 2018] [:info] [pid 5518] mod_wsgi (pid=5518): Python has shutdown.
[Thu Jun 21 16:07:41.391393 2018] [:info] [pid 5524] mod_wsgi (pid=5524): Destroying interpreters.
[Thu Jun 21 16:07:41.391410 2018] [:info] [pid 5524] mod_wsgi (pid=5524): Cleanup interpreter ''.
[Thu Jun 21 16:07:41.394489 2018] [:info] [pid 5524] mod_wsgi (pid=5524): Terminating Python.
[Thu Jun 21 16:07:41.396100 2018] [:info] [pid 5524] mod_wsgi (pid=5524): Python has shutdown.
[Thu Jun 21 16:07:42.343474 2018] [:info] [pid 5084] mod_wsgi (pid=5084): Shutdown requested 'ipa'.
[Thu Jun 21 16:07:42.343485 2018] [:info] [pid 5083] mod_wsgi (pid=5083): Shutdown requested 'ipa'.
[Thu Jun 21 16:07:42.343550 2018] [:info] [pid 5084] mod_wsgi (pid=5084): Stopping process 'ipa'.
[Thu Jun 21 16:07:42.343551 2018] [:info] [pid 5083] mod_wsgi (pid=5083): Stopping process 'ipa'.
[Thu Jun 21 16:07:42.343560 2018] [:info] [pid 5084] mod_wsgi (pid=5084): Destroying interpreters.
[Thu Jun 21 16:07:42.343564 2018] [:info] [pid 5083] mod_wsgi (pid=5083): Destroying interpreters.
[Thu Jun 21 16:07:42.343570 2018] [:info] [pid 5084] mod_wsgi (pid=5084): Destroy interpreter 'ipa'.
[Thu Jun 21 16:07:42.343572 2018] [:info] [pid 5083] mod_wsgi (pid=5083): Destroy interpreter 'ipa'.
[Thu Jun 21 16:07:42.353413 2018] [:info] [pid 5082] mod_wsgi (pid=5082): Shutdown requested 'kdcproxy'.
[Thu Jun 21 16:07:42.353411 2018] [:info] [pid 5081] mod_wsgi (pid=5081): Shutdown requested 'kdcproxy'.
[Thu Jun 21 16:07:42.353520 2018] [:info] [pid 5082] mod_wsgi (pid=5082): Stopping process 'kdcproxy'.
[Thu Jun 21 16:07:42.353522 2018] [:info] [pid 5081] mod_wsgi (pid=5081): Stopping process 'kdcproxy'.
[Thu Jun 21 16:07:42.353528 2018] [:info] [pid 5082] mod_wsgi (pid=5082): Destroying interpreters.
[Thu Jun 21 16:07:42.353530 2018] [:info] [pid 5081] mod_wsgi (pid=5081): Destroying interpreters.
[Thu Jun 21 16:07:42.353535 2018] [:info] [pid 5082] mod_wsgi (pid=5082): Destroy interpreter 'kdcproxy'.
[Thu Jun 21 16:07:42.353536 2018] [:info] [pid 5081] mod_wsgi (pid=5081): Destroy interpreter 'kdcproxy'.
[Thu Jun 21 16:07:42.355921 2018] [:info] [pid 5082] mod_wsgi (pid=5082): Cleanup interpreter ''.
[Thu Jun 21 16:07:42.355937 2018] [:info] [pid 5081] mod_wsgi (pid=5081): Cleanup interpreter ''.
[Thu Jun 21 16:07:42.357197 2018] [:info] [pid 5082] mod_wsgi (pid=5082): Terminating Python.
[Thu Jun 21 16:07:42.357198 2018] [:info] [pid 5081] mod_wsgi (pid=5081): Terminating Python.
[Thu Jun 21 16:07:42.360868 2018] [:info] [pid 5082] mod_wsgi (pid=5082): Python has shutdown.
[Thu Jun 21 16:07:42.360902 2018] [:info] [pid 5081] mod_wsgi (pid=5081): Python has shutdown.
[Thu Jun 21 16:07:42.362235 2018] [:info] [pid 5075] Shutting down SSL Session ID Cache
[Thu Jun 21 16:07:42.366216 2018] [:info] [pid 5084] mod_wsgi (pid=5084): Cleanup interpreter ''.
[Thu Jun 21 16:07:42.367756 2018] [:info] [pid 5084] mod_wsgi (pid=5084): Terminating Python.
[Thu Jun 21 16:07:42.373382 2018] [:info] [pid 5083] mod_wsgi (pid=5083): Cleanup interpreter ''.
[Thu Jun 21 16:07:42.375074 2018] [:info] [pid 5083] mod_wsgi (pid=5083): Terminating Python.
[Thu Jun 21 16:07:42.451658 2018] [:info] [pid 5084] mod_wsgi (pid=5084): Python has shutdown.
[Thu Jun 21 16:07:42.459532 2018] [:info] [pid 5083] mod_wsgi (pid=5083): Python has shutdown.
[Thu Jun 21 16:07:43.134282 2018] [core:notice] [pid 17500] SELinux policy enabled; httpd running as context system_u:system_r:httpd_t:s0
[Thu Jun 21 16:07:43.135077 2018] [suexec:notice] [pid 17500] AH01232: suEXEC mechanism enabled (wrapper: /usr/sbin/suexec)
[Thu Jun 21 16:07:43.135835 2018] [:info] [pid 17500] Initializing SSL Session Cache of size 10000. SSL3/TLS timeout = 86400.
[Thu Jun 21 16:07:43.175950 2018] [:debug] [pid 17500] nss_engine_init.c(204): Checking permissions for user apache: uid 48 gid 48
[Thu Jun 21 16:07:43.176153 2018] [:debug] [pid 17500] nss_engine_init.c(262): Permissions check out ok
[Thu Jun 21 16:07:43.269745 2018] [:debug] [pid 17500] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:43.269788 2018] [:info] [pid 17500] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:43.269793 2018] [:info] [pid 17500] Init: Initializing (virtual) servers for SSL
[Thu Jun 21 16:07:43.273971 2018] [:info] [pid 17500] Server: Apache/2.4.6, Interface: mod_nss/1.0.14, Library: NSS/3.28.4
[Thu Jun 21 16:07:43.274025 2018] [:info] [pid 17500] Shutting down SSL Session ID Cache
[Thu Jun 21 16:07:43.360655 2018] [auth_digest:notice] [pid 17500] AH01757: generating secret for digest authentication ...
[Thu Jun 21 16:07:43.360680 2018] [auth_digest:debug] [pid 17500] mod_auth_digest.c(250): AH01759: done
[Thu Jun 21 16:07:43.361350 2018] [slotmem_shm:debug] [pid 17500] mod_slotmem_shm.c(448): AH02301: attach looking for /run/httpd/slotmem-shm-mod_heartmonitor.shm
[Thu Jun 21 16:07:43.361375 2018] [lbmethod_heartbeat:notice] [pid 17500] AH02282: No slotmem from mod_heartmonitor
[Thu Jun 21 16:07:43.361444 2018] [:info] [pid 17500] Initializing SSL Session Cache of size 10000. SSL3/TLS timeout = 86400.
[Thu Jun 21 16:07:43.402052 2018] [:info] [pid 17500] Server: Apache/2.4.6, Interface: mod_nss/1.0.14, Library: NSS/3.28.4
[Thu Jun 21 16:07:43.402103 2018] [:debug] [pid 17500] mod_wsgi.c(10530): mod_wsgi (pid=17500): Socket for 'kdcproxy' is '/run/httpd/wsgi.17500.0.1.sock'.
[Thu Jun 21 16:07:43.403481 2018] [:debug] [pid 17500] mod_wsgi.c(10530): mod_wsgi (pid=17500): Socket for 'ipa' is '/run/httpd/wsgi.17500.0.2.sock'.
[Thu Jun 21 16:07:43.406092 2018] [:info] [pid 17513] mod_wsgi (pid=17513): Starting process 'kdcproxy' with uid=388, gid=388 and threads=15.
[Thu Jun 21 16:07:43.407481 2018] [:info] [pid 17515] mod_wsgi (pid=17515): Starting process 'ipa' with uid=387, gid=387 and threads=1.
[Thu Jun 21 16:07:43.408223 2018] [mpm_prefork:notice] [pid 17500] AH00163: Apache/2.4.6 (CentOS) mod_auth_gssapi/1.5.1 mod_nss/1.0.14 NSS/3.28.4 mod_wsgi/3.4 Python/2.7.5 configured -- resuming normal operations
[Thu Jun 21 16:07:43.408234 2018] [mpm_prefork:info] [pid 17500] AH00164: Server built: Apr 20 2018 18:10:38
[Thu Jun 21 16:07:43.408245 2018] [core:notice] [pid 17500] AH00094: Command line: '/usr/sbin/httpd -D FOREGROUND'
[Thu Jun 21 16:07:43.408262 2018] [mpm_prefork:debug] [pid 17500] prefork.c(1005): AH00165: Accept mutex: sysvsem (default: sysvsem)
[Thu Jun 21 16:07:43.410968 2018] [:info] [pid 17515] mod_wsgi (pid=17515): Initializing Python.
[Thu Jun 21 16:07:43.420058 2018] [:info] [pid 17515] mod_wsgi (pid=17515): Attach interpreter ''.
[Thu Jun 21 16:07:43.422277 2018] [:info] [pid 17512] mod_wsgi (pid=17512): Starting process 'kdcproxy' with uid=388, gid=388 and threads=15.
[Thu Jun 21 16:07:43.423428 2018] [:info] [pid 17514] mod_wsgi (pid=17514): Starting process 'ipa' with uid=387, gid=387 and threads=1.
[Thu Jun 21 16:07:43.424400 2018] [:info] [pid 17514] mod_wsgi (pid=17514): Initializing Python.
[Thu Jun 21 16:07:43.433089 2018] [:info] [pid 17514] mod_wsgi (pid=17514): Attach interpreter ''.
[Thu Jun 21 16:07:43.436513 2018] [proxy:debug] [pid 17518] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.436547 2018] [proxy:debug] [pid 17518] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.436582 2018] [proxy:debug] [pid 17518] proxy_util.c(1936): AH00931: initialized single connection worker in child 17518 for (localhost)
[Thu Jun 21 16:07:43.436588 2018] [proxy:debug] [pid 17518] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.436598 2018] [proxy:debug] [pid 17518] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.436618 2018] [proxy:debug] [pid 17518] proxy_util.c(1936): AH00931: initialized single connection worker in child 17518 for (localhost)
[Thu Jun 21 16:07:43.436622 2018] [proxy:debug] [pid 17518] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.436628 2018] [proxy:debug] [pid 17518] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.436641 2018] [proxy:debug] [pid 17518] proxy_util.c(1936): AH00931: initialized single connection worker in child 17518 for (localhost)
[Thu Jun 21 16:07:43.436646 2018] [proxy:debug] [pid 17518] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.436651 2018] [proxy:debug] [pid 17518] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.436665 2018] [proxy:debug] [pid 17518] proxy_util.c(1936): AH00931: initialized single connection worker in child 17518 for (localhost)
[Thu Jun 21 16:07:43.436670 2018] [proxy:debug] [pid 17518] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.436676 2018] [proxy:debug] [pid 17518] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.436688 2018] [proxy:debug] [pid 17518] proxy_util.c(1936): AH00931: initialized single connection worker in child 17518 for (localhost)
[Thu Jun 21 16:07:43.436693 2018] [proxy:debug] [pid 17518] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:43.436696 2018] [proxy:debug] [pid 17518] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:43.436711 2018] [proxy:debug] [pid 17518] proxy_util.c(1936): AH00931: initialized single connection worker in child 17518 for (localhost)
[Thu Jun 21 16:07:43.436733 2018] [proxy:debug] [pid 17518] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:43.436736 2018] [proxy:debug] [pid 17518] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:43.436748 2018] [proxy:debug] [pid 17518] proxy_util.c(1936): AH00931: initialized single connection worker in child 17518 for (*)
[Thu Jun 21 16:07:43.445504 2018] [proxy:debug] [pid 17517] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.445540 2018] [proxy:debug] [pid 17517] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.445577 2018] [proxy:debug] [pid 17517] proxy_util.c(1936): AH00931: initialized single connection worker in child 17517 for (localhost)
[Thu Jun 21 16:07:43.445583 2018] [proxy:debug] [pid 17517] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.445589 2018] [proxy:debug] [pid 17517] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.445608 2018] [proxy:debug] [pid 17517] proxy_util.c(1936): AH00931: initialized single connection worker in child 17517 for (localhost)
[Thu Jun 21 16:07:43.445613 2018] [proxy:debug] [pid 17517] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.445618 2018] [proxy:debug] [pid 17517] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.445631 2018] [proxy:debug] [pid 17517] proxy_util.c(1936): AH00931: initialized single connection worker in child 17517 for (localhost)
[Thu Jun 21 16:07:43.445636 2018] [proxy:debug] [pid 17517] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.445641 2018] [proxy:debug] [pid 17517] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.445654 2018] [proxy:debug] [pid 17517] proxy_util.c(1936): AH00931: initialized single connection worker in child 17517 for (localhost)
[Thu Jun 21 16:07:43.445658 2018] [proxy:debug] [pid 17517] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.445664 2018] [proxy:debug] [pid 17517] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.445677 2018] [proxy:debug] [pid 17517] proxy_util.c(1936): AH00931: initialized single connection worker in child 17517 for (localhost)
[Thu Jun 21 16:07:43.445682 2018] [proxy:debug] [pid 17517] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:43.445684 2018] [proxy:debug] [pid 17517] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:43.445702 2018] [proxy:debug] [pid 17517] proxy_util.c(1936): AH00931: initialized single connection worker in child 17517 for (localhost)
[Thu Jun 21 16:07:43.445723 2018] [proxy:debug] [pid 17517] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:43.445727 2018] [proxy:debug] [pid 17517] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:43.445738 2018] [proxy:debug] [pid 17517] proxy_util.c(1936): AH00931: initialized single connection worker in child 17517 for (*)
[Thu Jun 21 16:07:43.452753 2018] [:info] [pid 17515] mod_wsgi (pid=17515): Create interpreter 'ipa'.
[Thu Jun 21 16:07:43.453735 2018] [:info] [pid 17515] mod_wsgi (pid=17515, process='ipa', application='ipa'): Loading WSGI script '/usr/share/ipa/wsgi.py'.
[Thu Jun 21 16:07:43.456492 2018] [proxy:debug] [pid 17516] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.456525 2018] [proxy:debug] [pid 17516] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.456555 2018] [proxy:debug] [pid 17516] proxy_util.c(1936): AH00931: initialized single connection worker in child 17516 for (localhost)
[Thu Jun 21 16:07:43.456561 2018] [proxy:debug] [pid 17516] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.456570 2018] [proxy:debug] [pid 17516] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.456588 2018] [proxy:debug] [pid 17516] proxy_util.c(1936): AH00931: initialized single connection worker in child 17516 for (localhost)
[Thu Jun 21 16:07:43.456593 2018] [proxy:debug] [pid 17516] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.456599 2018] [proxy:debug] [pid 17516] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.456614 2018] [proxy:debug] [pid 17516] proxy_util.c(1936): AH00931: initialized single connection worker in child 17516 for (localhost)
[Thu Jun 21 16:07:43.456619 2018] [proxy:debug] [pid 17516] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.456624 2018] [proxy:debug] [pid 17516] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.456636 2018] [proxy:debug] [pid 17516] proxy_util.c(1936): AH00931: initialized single connection worker in child 17516 for (localhost)
[Thu Jun 21 16:07:43.456641 2018] [proxy:debug] [pid 17516] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.456646 2018] [proxy:debug] [pid 17516] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.456658 2018] [proxy:debug] [pid 17516] proxy_util.c(1936): AH00931: initialized single connection worker in child 17516 for (localhost)
[Thu Jun 21 16:07:43.456663 2018] [proxy:debug] [pid 17516] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:43.456665 2018] [proxy:debug] [pid 17516] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:43.456680 2018] [proxy:debug] [pid 17516] proxy_util.c(1936): AH00931: initialized single connection worker in child 17516 for (localhost)
[Thu Jun 21 16:07:43.456700 2018] [proxy:debug] [pid 17516] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:43.456704 2018] [proxy:debug] [pid 17516] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:43.456715 2018] [proxy:debug] [pid 17516] proxy_util.c(1936): AH00931: initialized single connection worker in child 17516 for (*)
[Thu Jun 21 16:07:43.463629 2018] [:info] [pid 17514] mod_wsgi (pid=17514): Create interpreter 'ipa'.
[Thu Jun 21 16:07:43.464606 2018] [:info] [pid 17514] mod_wsgi (pid=17514, process='ipa', application='ipa'): Loading WSGI script '/usr/share/ipa/wsgi.py'.
[Thu Jun 21 16:07:43.467493 2018] [proxy:debug] [pid 17520] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.467527 2018] [proxy:debug] [pid 17520] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.467558 2018] [proxy:debug] [pid 17520] proxy_util.c(1936): AH00931: initialized single connection worker in child 17520 for (localhost)
[Thu Jun 21 16:07:43.467565 2018] [proxy:debug] [pid 17520] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.467571 2018] [proxy:debug] [pid 17520] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.467589 2018] [proxy:debug] [pid 17520] proxy_util.c(1936): AH00931: initialized single connection worker in child 17520 for (localhost)
[Thu Jun 21 16:07:43.467594 2018] [proxy:debug] [pid 17520] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.467600 2018] [proxy:debug] [pid 17520] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.467617 2018] [proxy:debug] [pid 17520] proxy_util.c(1936): AH00931: initialized single connection worker in child 17520 for (localhost)
[Thu Jun 21 16:07:43.467621 2018] [proxy:debug] [pid 17520] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.467627 2018] [proxy:debug] [pid 17520] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.467640 2018] [proxy:debug] [pid 17520] proxy_util.c(1936): AH00931: initialized single connection worker in child 17520 for (localhost)
[Thu Jun 21 16:07:43.467644 2018] [proxy:debug] [pid 17520] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.467650 2018] [proxy:debug] [pid 17520] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.467668 2018] [proxy:debug] [pid 17520] proxy_util.c(1936): AH00931: initialized single connection worker in child 17520 for (localhost)
[Thu Jun 21 16:07:43.467673 2018] [proxy:debug] [pid 17520] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:43.467676 2018] [proxy:debug] [pid 17520] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:43.467691 2018] [proxy:debug] [pid 17520] proxy_util.c(1936): AH00931: initialized single connection worker in child 17520 for (localhost)
[Thu Jun 21 16:07:43.467712 2018] [proxy:debug] [pid 17520] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:43.467715 2018] [proxy:debug] [pid 17520] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:43.467727 2018] [proxy:debug] [pid 17520] proxy_util.c(1936): AH00931: initialized single connection worker in child 17520 for (*)
[Thu Jun 21 16:07:43.478497 2018] [proxy:debug] [pid 17519] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.478534 2018] [proxy:debug] [pid 17519] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.478565 2018] [proxy:debug] [pid 17519] proxy_util.c(1936): AH00931: initialized single connection worker in child 17519 for (localhost)
[Thu Jun 21 16:07:43.478571 2018] [proxy:debug] [pid 17519] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.478576 2018] [proxy:debug] [pid 17519] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.478595 2018] [proxy:debug] [pid 17519] proxy_util.c(1936): AH00931: initialized single connection worker in child 17519 for (localhost)
[Thu Jun 21 16:07:43.478599 2018] [proxy:debug] [pid 17519] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.478604 2018] [proxy:debug] [pid 17519] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.478618 2018] [proxy:debug] [pid 17519] proxy_util.c(1936): AH00931: initialized single connection worker in child 17519 for (localhost)
[Thu Jun 21 16:07:43.478622 2018] [proxy:debug] [pid 17519] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.478628 2018] [proxy:debug] [pid 17519] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.478640 2018] [proxy:debug] [pid 17519] proxy_util.c(1936): AH00931: initialized single connection worker in child 17519 for (localhost)
[Thu Jun 21 16:07:43.478644 2018] [proxy:debug] [pid 17519] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:43.478650 2018] [proxy:debug] [pid 17519] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:43.478662 2018] [proxy:debug] [pid 17519] proxy_util.c(1936): AH00931: initialized single connection worker in child 17519 for (localhost)
[Thu Jun 21 16:07:43.478667 2018] [proxy:debug] [pid 17519] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:43.478670 2018] [proxy:debug] [pid 17519] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:43.478688 2018] [proxy:debug] [pid 17519] proxy_util.c(1936): AH00931: initialized single connection worker in child 17519 for (localhost)
[Thu Jun 21 16:07:43.478708 2018] [proxy:debug] [pid 17519] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:43.478712 2018] [proxy:debug] [pid 17519] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:43.478723 2018] [proxy:debug] [pid 17519] proxy_util.c(1936): AH00931: initialized single connection worker in child 17519 for (*)
[Thu Jun 21 16:07:43.480981 2018] [:info] [pid 17513] mod_wsgi (pid=17513): Initializing Python.
[Thu Jun 21 16:07:43.489798 2018] [:info] [pid 17513] mod_wsgi (pid=17513): Attach interpreter ''.
[Thu Jun 21 16:07:43.491452 2018] [:info] [pid 17512] mod_wsgi (pid=17512): Initializing Python.
[Thu Jun 21 16:07:43.500106 2018] [:info] [pid 17512] mod_wsgi (pid=17512): Attach interpreter ''.
[Thu Jun 21 16:07:43.518347 2018] [:info] [pid 17513] mod_wsgi (pid=17513): Create interpreter 'kdcproxy'.
[Thu Jun 21 16:07:43.519336 2018] [:info] [pid 17513] mod_wsgi (pid=17513, process='kdcproxy', application='kdcproxy'): Loading WSGI script '/usr/lib/python2.7/site-packages/kdcproxy/__init__.py'.
[Thu Jun 21 16:07:43.528731 2018] [:info] [pid 17512] mod_wsgi (pid=17512): Create interpreter 'kdcproxy'.
[Thu Jun 21 16:07:43.529729 2018] [:info] [pid 17512] mod_wsgi (pid=17512, process='kdcproxy', application='kdcproxy'): Loading WSGI script '/usr/lib/python2.7/site-packages/kdcproxy/__init__.py'.
[Thu Jun 21 16:07:43.805047 2018] [:debug] [pid 17518] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:43.805086 2018] [:info] [pid 17518] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:43.809400 2018] [:info] [pid 17518] mod_wsgi (pid=17518): Initializing Python.
[Thu Jun 21 16:07:43.829110 2018] [:info] [pid 17518] mod_wsgi (pid=17518): Attach interpreter ''.
[Thu Jun 21 16:07:43.867676 2018] [:debug] [pid 17519] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:43.867720 2018] [:info] [pid 17519] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:43.871992 2018] [:info] [pid 17519] mod_wsgi (pid=17519): Initializing Python.
[Thu Jun 21 16:07:43.889500 2018] [:debug] [pid 17516] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:43.889548 2018] [:info] [pid 17516] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:43.893828 2018] [:info] [pid 17516] mod_wsgi (pid=17516): Initializing Python.
[Thu Jun 21 16:07:43.899524 2018] [:debug] [pid 17517] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:43.899557 2018] [:info] [pid 17517] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:43.903825 2018] [:info] [pid 17517] mod_wsgi (pid=17517): Initializing Python.
[Thu Jun 21 16:07:43.910528 2018] [:debug] [pid 17520] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:43.910558 2018] [:info] [pid 17520] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:43.912903 2018] [:info] [pid 17519] mod_wsgi (pid=17519): Attach interpreter ''.
[Thu Jun 21 16:07:43.914925 2018] [:info] [pid 17520] mod_wsgi (pid=17520): Initializing Python.
[Thu Jun 21 16:07:43.922166 2018] [:info] [pid 17516] mod_wsgi (pid=17516): Attach interpreter ''.
[Thu Jun 21 16:07:43.934355 2018] [:info] [pid 17520] mod_wsgi (pid=17520): Attach interpreter ''.
[Thu Jun 21 16:07:43.936589 2018] [:info] [pid 17517] mod_wsgi (pid=17517): Attach interpreter ''.
[Thu Jun 21 16:07:44.072866 2018] [:error] [pid 17515] ipa: DEBUG: importing all plugin modules in ipaserver.plugins...
[Thu Jun 21 16:07:44.073519 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.aci
[Thu Jun 21 16:07:44.082936 2018] [:error] [pid 17514] ipa: DEBUG: importing all plugin modules in ipaserver.plugins...
[Thu Jun 21 16:07:44.094597 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.aci
[Thu Jun 21 16:07:44.103148 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.automember
[Thu Jun 21 16:07:44.113844 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.automember
[Thu Jun 21 16:07:44.129076 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.automount
[Thu Jun 21 16:07:44.132513 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.baseldap
[Thu Jun 21 16:07:44.132609 2018] [:error] [pid 17514] ipa: DEBUG: ipaserver.plugins.baseldap is not a valid plugin module
[Thu Jun 21 16:07:44.132653 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.baseuser
[Thu Jun 21 16:07:44.140742 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.automount
[Thu Jun 21 16:07:44.144201 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.baseldap
[Thu Jun 21 16:07:44.144295 2018] [:error] [pid 17515] ipa: DEBUG: ipaserver.plugins.baseldap is not a valid plugin module
[Thu Jun 21 16:07:44.144338 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.baseuser
[Thu Jun 21 16:07:44.155941 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.batch
[Thu Jun 21 16:07:44.156539 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.ca
[Thu Jun 21 16:07:44.167754 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.batch
[Thu Jun 21 16:07:44.168320 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.ca
[Thu Jun 21 16:07:44.178790 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.caacl
[Thu Jun 21 16:07:44.178988 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.cert
[Thu Jun 21 16:07:44.179107 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.certmap
[Thu Jun 21 16:07:44.184529 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.certprofile
[Thu Jun 21 16:07:44.184648 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.config
[Thu Jun 21 16:07:44.190286 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.caacl
[Thu Jun 21 16:07:44.190502 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.cert
[Thu Jun 21 16:07:44.190557 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.delegation
[Thu Jun 21 16:07:44.190627 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.certmap
[Thu Jun 21 16:07:44.192191 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.dns
[Thu Jun 21 16:07:44.196083 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.certprofile
[Thu Jun 21 16:07:44.196203 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.config
[Thu Jun 21 16:07:44.202106 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.delegation
[Thu Jun 21 16:07:44.203768 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.dns
[Thu Jun 21 16:07:44.232757 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.dnsserver
[Thu Jun 21 16:07:44.233927 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.dogtag
[Thu Jun 21 16:07:44.244282 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.dnsserver
[Thu Jun 21 16:07:44.245455 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.dogtag
[Thu Jun 21 16:07:44.332638 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.domainlevel
[Thu Jun 21 16:07:44.333591 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.group
[Thu Jun 21 16:07:44.343093 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.domainlevel
[Thu Jun 21 16:07:44.344013 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.group
[Thu Jun 21 16:07:44.375068 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.hbac
[Thu Jun 21 16:07:44.375245 2018] [:error] [pid 17514] ipa: DEBUG: ipaserver.plugins.hbac is not a valid plugin module
[Thu Jun 21 16:07:44.375294 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.hbacrule
[Thu Jun 21 16:07:44.375505 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.hbacsvc
[Thu Jun 21 16:07:44.376508 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.hbacsvcgroup
[Thu Jun 21 16:07:44.377425 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.hbactest
[Thu Jun 21 16:07:44.379312 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.host
[Thu Jun 21 16:07:44.384997 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.hbac
[Thu Jun 21 16:07:44.385163 2018] [:error] [pid 17515] ipa: DEBUG: ipaserver.plugins.hbac is not a valid plugin module
[Thu Jun 21 16:07:44.385210 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.hbacrule
[Thu Jun 21 16:07:44.385407 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.hbacsvc
[Thu Jun 21 16:07:44.386388 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.hbacsvcgroup
[Thu Jun 21 16:07:44.387291 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.hbactest
[Thu Jun 21 16:07:44.387313 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.hostgroup
[Thu Jun 21 16:07:44.387458 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.idrange
[Thu Jun 21 16:07:44.389194 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.host
[Thu Jun 21 16:07:44.389877 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.idviews
[Thu Jun 21 16:07:44.390098 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.internal
[Thu Jun 21 16:07:44.392725 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.join
[Thu Jun 21 16:07:44.393562 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.krbtpolicy
[Thu Jun 21 16:07:44.394561 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.ldap2
[Thu Jun 21 16:07:44.395246 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.location
[Thu Jun 21 16:07:44.396419 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.migration
[Thu Jun 21 16:07:44.397225 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.hostgroup
[Thu Jun 21 16:07:44.397351 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.idrange
[Thu Jun 21 16:07:44.399778 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.idviews
[Thu Jun 21 16:07:44.400006 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.internal
[Thu Jun 21 16:07:44.402602 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.misc
[Thu Jun 21 16:07:44.402647 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.join
[Thu Jun 21 16:07:44.403323 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.netgroup
[Thu Jun 21 16:07:44.403454 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.krbtpolicy
[Thu Jun 21 16:07:44.403478 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.otp
[Thu Jun 21 16:07:44.403624 2018] [:error] [pid 17514] ipa: DEBUG: ipaserver.plugins.otp is not a valid plugin module
[Thu Jun 21 16:07:44.403669 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.otpconfig
[Thu Jun 21 16:07:44.404466 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.ldap2
[Thu Jun 21 16:07:44.404537 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.otptoken
[Thu Jun 21 16:07:44.405142 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.location
[Thu Jun 21 16:07:44.406292 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.migration
[Thu Jun 21 16:07:44.409399 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.passwd
[Thu Jun 21 16:07:44.410249 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.permission
[Thu Jun 21 16:07:44.412557 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.misc
[Thu Jun 21 16:07:44.413263 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.netgroup
[Thu Jun 21 16:07:44.413410 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.otp
[Thu Jun 21 16:07:44.413558 2018] [:error] [pid 17515] ipa: DEBUG: ipaserver.plugins.otp is not a valid plugin module
[Thu Jun 21 16:07:44.413602 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.otpconfig
[Thu Jun 21 16:07:44.414467 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.otptoken
[Thu Jun 21 16:07:44.415447 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.ping
[Thu Jun 21 16:07:44.415689 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.pkinit
[Thu Jun 21 16:07:44.416742 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.privilege
[Thu Jun 21 16:07:44.416890 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.pwpolicy
[Thu Jun 21 16:07:44.419418 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.passwd
[Thu Jun 21 16:07:44.419666 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.rabase
[Thu Jun 21 16:07:44.419744 2018] [:error] [pid 17514] ipa: DEBUG: ipaserver.plugins.rabase is not a valid plugin module
[Thu Jun 21 16:07:44.419785 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.radiusproxy
[Thu Jun 21 16:07:44.420265 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.permission
[Thu Jun 21 16:07:44.421664 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.realmdomains
[Thu Jun 21 16:07:44.422648 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.role
[Thu Jun 21 16:07:44.423725 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.schema
[Thu Jun 21 16:07:44.425486 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.ping
[Thu Jun 21 16:07:44.425726 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.pkinit
[Thu Jun 21 16:07:44.426773 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.privilege
[Thu Jun 21 16:07:44.426914 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.pwpolicy
[Thu Jun 21 16:07:44.428642 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.selfservice
[Thu Jun 21 16:07:44.429685 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.rabase
[Thu Jun 21 16:07:44.429769 2018] [:error] [pid 17515] ipa: DEBUG: ipaserver.plugins.rabase is not a valid plugin module
[Thu Jun 21 16:07:44.429812 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.radiusproxy
[Thu Jun 21 16:07:44.430188 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.selinuxusermap
[Thu Jun 21 16:07:44.430345 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.server
[Thu Jun 21 16:07:44.431674 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.realmdomains
[Thu Jun 21 16:07:44.432669 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.role
[Thu Jun 21 16:07:44.433746 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.schema
[Thu Jun 21 16:07:44.438719 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.selfservice
[Thu Jun 21 16:07:44.440266 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.selinuxusermap
[Thu Jun 21 16:07:44.440442 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.server
[Thu Jun 21 16:07:44.681732 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.serverrole
[Thu Jun 21 16:07:44.683149 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.serverroles
[Thu Jun 21 16:07:44.683450 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.service
[Thu Jun 21 16:07:44.683686 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.servicedelegation
[Thu Jun 21 16:07:44.686016 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.session
[Thu Jun 21 16:07:44.686259 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.stageuser
[Thu Jun 21 16:07:44.688110 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.sudo
[Thu Jun 21 16:07:44.688279 2018] [:error] [pid 17514] ipa: DEBUG: ipaserver.plugins.sudo is not a valid plugin module
[Thu Jun 21 16:07:44.688327 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.sudocmd
[Thu Jun 21 16:07:44.689183 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.sudocmdgroup
[Thu Jun 21 16:07:44.690377 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.sudorule
[Thu Jun 21 16:07:44.694043 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.serverrole
[Thu Jun 21 16:07:44.695453 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.serverroles
[Thu Jun 21 16:07:44.695740 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.service
[Thu Jun 21 16:07:44.695898 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.topology
[Thu Jun 21 16:07:44.695969 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.servicedelegation
[Thu Jun 21 16:07:44.698280 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.session
[Thu Jun 21 16:07:44.698537 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.stageuser
[Thu Jun 21 16:07:44.699191 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.trust
[Thu Jun 21 16:07:44.700334 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.sudo
[Thu Jun 21 16:07:44.700548 2018] [:error] [pid 17515] ipa: DEBUG: ipaserver.plugins.sudo is not a valid plugin module
[Thu Jun 21 16:07:44.700602 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.sudocmd
[Thu Jun 21 16:07:44.701457 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.sudocmdgroup
[Thu Jun 21 16:07:44.702646 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.sudorule
[Thu Jun 21 16:07:44.707949 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.user
[Thu Jun 21 16:07:44.708205 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.vault
[Thu Jun 21 16:07:44.708229 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.topology
[Thu Jun 21 16:07:44.711599 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.trust
[Thu Jun 21 16:07:44.714490 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.virtual
[Thu Jun 21 16:07:44.714579 2018] [:error] [pid 17514] ipa: DEBUG: ipaserver.plugins.virtual is not a valid plugin module
[Thu Jun 21 16:07:44.714622 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.whoami
[Thu Jun 21 16:07:44.715293 2018] [:error] [pid 17514] ipa: DEBUG: importing plugin module ipaserver.plugins.xmlserver
[Thu Jun 21 16:07:44.720411 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.user
[Thu Jun 21 16:07:44.720671 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.vault
[Thu Jun 21 16:07:44.727056 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.virtual
[Thu Jun 21 16:07:44.727152 2018] [:error] [pid 17515] ipa: DEBUG: ipaserver.plugins.virtual is not a valid plugin module
[Thu Jun 21 16:07:44.727197 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.whoami
[Thu Jun 21 16:07:44.727905 2018] [:error] [pid 17515] ipa: DEBUG: importing plugin module ipaserver.plugins.xmlserver
[Thu Jun 21 16:07:44.849813 2018] [:error] [pid 17514] ipa: DEBUG: Mounting ipaserver.rpcserver.jsonserver_session() at '/session/json'
[Thu Jun 21 16:07:44.863807 2018] [:error] [pid 17515] ipa: DEBUG: Mounting ipaserver.rpcserver.jsonserver_session() at '/session/json'
[Thu Jun 21 16:07:44.975443 2018] [:error] [pid 17514] ipa: DEBUG: Mounting ipaserver.rpcserver.change_password() at '/session/change_password'
[Thu Jun 21 16:07:44.984071 2018] [:error] [pid 17514] ipa: DEBUG: Mounting ipaserver.rpcserver.login_x509() at '/session/login_x509'
[Thu Jun 21 16:07:44.990626 2018] [:error] [pid 17515] ipa: DEBUG: Mounting ipaserver.rpcserver.change_password() at '/session/change_password'
[Thu Jun 21 16:07:44.999301 2018] [:error] [pid 17515] ipa: DEBUG: Mounting ipaserver.rpcserver.login_x509() at '/session/login_x509'
[Thu Jun 21 16:07:45.022003 2018] [:error] [pid 17514] ipa: DEBUG: Mounting ipaserver.rpcserver.login_password() at '/session/login_password'
[Thu Jun 21 16:07:45.040093 2018] [:error] [pid 17515] ipa: DEBUG: Mounting ipaserver.rpcserver.login_password() at '/session/login_password'
[Thu Jun 21 16:07:45.205490 2018] [:error] [pid 17514] ipa: DEBUG: Mounting ipaserver.rpcserver.sync_token() at '/session/sync_token'
[Thu Jun 21 16:07:45.221105 2018] [:error] [pid 17514] ipa: DEBUG: Mounting ipaserver.rpcserver.xmlserver_session() at '/session/xml'
[Thu Jun 21 16:07:45.226415 2018] [:error] [pid 17515] ipa: DEBUG: Mounting ipaserver.rpcserver.sync_token() at '/session/sync_token'
[Thu Jun 21 16:07:45.242137 2018] [:error] [pid 17515] ipa: DEBUG: Mounting ipaserver.rpcserver.xmlserver_session() at '/session/xml'
[Thu Jun 21 16:07:45.635429 2018] [:error] [pid 17514] ipa: DEBUG: Mounting ipaserver.rpcserver.xmlserver() at '/xml'
[Thu Jun 21 16:07:45.666628 2018] [:error] [pid 17515] ipa: DEBUG: Mounting ipaserver.rpcserver.xmlserver() at '/xml'
[Thu Jun 21 16:07:45.720871 2018] [:error] [pid 17514] ipa: DEBUG: Mounting ipaserver.rpcserver.jsonserver_kerb() at '/json'
[Thu Jun 21 16:07:45.753076 2018] [:error] [pid 17515] ipa: DEBUG: Mounting ipaserver.rpcserver.jsonserver_kerb() at '/json'
[Thu Jun 21 16:07:45.828505 2018] [:error] [pid 17514] ipa: DEBUG: Mounting ipaserver.rpcserver.login_kerberos() at '/session/login_kerberos'
[Thu Jun 21 16:07:45.862570 2018] [:error] [pid 17515] ipa: DEBUG: Mounting ipaserver.rpcserver.login_kerberos() at '/session/login_kerberos'
[Thu Jun 21 16:07:45.936853 2018] [:error] [pid 17514] ipa: INFO: *** PROCESS START ***
[Thu Jun 21 16:07:45.971940 2018] [:error] [pid 17515] ipa: INFO: *** PROCESS START ***
[Thu Jun 21 16:07:47.419959 2018] [proxy:debug] [pid 17588] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:47.420005 2018] [proxy:debug] [pid 17588] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:47.420040 2018] [proxy:debug] [pid 17588] proxy_util.c(1936): AH00931: initialized single connection worker in child 17588 for (localhost)
[Thu Jun 21 16:07:47.420046 2018] [proxy:debug] [pid 17588] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:47.420052 2018] [proxy:debug] [pid 17588] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:47.420072 2018] [proxy:debug] [pid 17588] proxy_util.c(1936): AH00931: initialized single connection worker in child 17588 for (localhost)
[Thu Jun 21 16:07:47.420077 2018] [proxy:debug] [pid 17588] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:47.420083 2018] [proxy:debug] [pid 17588] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:47.420101 2018] [proxy:debug] [pid 17588] proxy_util.c(1936): AH00931: initialized single connection worker in child 17588 for (localhost)
[Thu Jun 21 16:07:47.420106 2018] [proxy:debug] [pid 17588] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:47.420111 2018] [proxy:debug] [pid 17588] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:47.420126 2018] [proxy:debug] [pid 17588] proxy_util.c(1936): AH00931: initialized single connection worker in child 17588 for (localhost)
[Thu Jun 21 16:07:47.420130 2018] [proxy:debug] [pid 17588] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:47.420136 2018] [proxy:debug] [pid 17588] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:47.420153 2018] [proxy:debug] [pid 17588] proxy_util.c(1936): AH00931: initialized single connection worker in child 17588 for (localhost)
[Thu Jun 21 16:07:47.420158 2018] [proxy:debug] [pid 17588] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:47.420160 2018] [proxy:debug] [pid 17588] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:47.420176 2018] [proxy:debug] [pid 17588] proxy_util.c(1936): AH00931: initialized single connection worker in child 17588 for (localhost)
[Thu Jun 21 16:07:47.420198 2018] [proxy:debug] [pid 17588] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:47.420202 2018] [proxy:debug] [pid 17588] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:47.420215 2018] [proxy:debug] [pid 17588] proxy_util.c(1936): AH00931: initialized single connection worker in child 17588 for (*)
[Thu Jun 21 16:07:47.505954 2018] [:debug] [pid 17588] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:47.505984 2018] [:info] [pid 17588] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:47.510193 2018] [:info] [pid 17588] mod_wsgi (pid=17588): Initializing Python.
[Thu Jun 21 16:07:47.518827 2018] [:info] [pid 17588] mod_wsgi (pid=17588): Attach interpreter ''.
[Thu Jun 21 16:07:48.322078 2018] [auth_gssapi:error] [pid 17520] [client 38.99.100.6:58782] NO AUTH DATA Client did not send any authentication headers, referer: https://example.ipa.EXAMPLE.com/ipa/ui/
[Thu Jun 21 16:07:48.422144 2018] [proxy:debug] [pid 17589] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:48.422194 2018] [proxy:debug] [pid 17589] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:48.422228 2018] [proxy:debug] [pid 17589] proxy_util.c(1936): AH00931: initialized single connection worker in child 17589 for (localhost)
[Thu Jun 21 16:07:48.422234 2018] [proxy:debug] [pid 17589] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:48.422240 2018] [proxy:debug] [pid 17589] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:48.422260 2018] [proxy:debug] [pid 17589] proxy_util.c(1936): AH00931: initialized single connection worker in child 17589 for (localhost)
[Thu Jun 21 16:07:48.422264 2018] [proxy:debug] [pid 17589] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:48.422270 2018] [proxy:debug] [pid 17589] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:48.422286 2018] [proxy:debug] [pid 17589] proxy_util.c(1936): AH00931: initialized single connection worker in child 17589 for (localhost)
[Thu Jun 21 16:07:48.422291 2018] [proxy:debug] [pid 17589] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:48.422297 2018] [proxy:debug] [pid 17589] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:48.422311 2018] [proxy:debug] [pid 17589] proxy_util.c(1936): AH00931: initialized single connection worker in child 17589 for (localhost)
[Thu Jun 21 16:07:48.422321 2018] [proxy:debug] [pid 17589] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:48.422326 2018] [proxy:debug] [pid 17589] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:48.422339 2018] [proxy:debug] [pid 17589] proxy_util.c(1936): AH00931: initialized single connection worker in child 17589 for (localhost)
[Thu Jun 21 16:07:48.422344 2018] [proxy:debug] [pid 17589] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:48.422347 2018] [proxy:debug] [pid 17589] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:48.422379 2018] [proxy:debug] [pid 17589] proxy_util.c(1936): AH00931: initialized single connection worker in child 17589 for (localhost)
[Thu Jun 21 16:07:48.422406 2018] [proxy:debug] [pid 17589] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:48.422410 2018] [proxy:debug] [pid 17589] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:48.422423 2018] [proxy:debug] [pid 17589] proxy_util.c(1936): AH00931: initialized single connection worker in child 17589 for (*)
[Thu Jun 21 16:07:48.423596 2018] [proxy:debug] [pid 17590] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:48.423640 2018] [proxy:debug] [pid 17590] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:48.423670 2018] [proxy:debug] [pid 17590] proxy_util.c(1936): AH00931: initialized single connection worker in child 17590 for (localhost)
[Thu Jun 21 16:07:48.423676 2018] [proxy:debug] [pid 17590] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:48.423681 2018] [proxy:debug] [pid 17590] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:48.423699 2018] [proxy:debug] [pid 17590] proxy_util.c(1936): AH00931: initialized single connection worker in child 17590 for (localhost)
[Thu Jun 21 16:07:48.423704 2018] [proxy:debug] [pid 17590] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:48.423709 2018] [proxy:debug] [pid 17590] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:48.423722 2018] [proxy:debug] [pid 17590] proxy_util.c(1936): AH00931: initialized single connection worker in child 17590 for (localhost)
[Thu Jun 21 16:07:48.423726 2018] [proxy:debug] [pid 17590] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:48.423732 2018] [proxy:debug] [pid 17590] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:48.423745 2018] [proxy:debug] [pid 17590] proxy_util.c(1936): AH00931: initialized single connection worker in child 17590 for (localhost)
[Thu Jun 21 16:07:48.423749 2018] [proxy:debug] [pid 17590] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:48.423755 2018] [proxy:debug] [pid 17590] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:48.423767 2018] [proxy:debug] [pid 17590] proxy_util.c(1936): AH00931: initialized single connection worker in child 17590 for (localhost)
[Thu Jun 21 16:07:48.423771 2018] [proxy:debug] [pid 17590] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:48.423774 2018] [proxy:debug] [pid 17590] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:48.423788 2018] [proxy:debug] [pid 17590] proxy_util.c(1936): AH00931: initialized single connection worker in child 17590 for (localhost)
[Thu Jun 21 16:07:48.423808 2018] [proxy:debug] [pid 17590] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:48.423815 2018] [proxy:debug] [pid 17590] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:48.423831 2018] [proxy:debug] [pid 17590] proxy_util.c(1936): AH00931: initialized single connection worker in child 17590 for (*)
[Thu Jun 21 16:07:48.508947 2018] [:debug] [pid 17589] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:48.508986 2018] [:info] [pid 17589] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:48.510091 2018] [:debug] [pid 17590] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:48.510119 2018] [:info] [pid 17590] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:48.513218 2018] [:info] [pid 17589] mod_wsgi (pid=17589): Initializing Python.
[Thu Jun 21 16:07:48.514341 2018] [:info] [pid 17590] mod_wsgi (pid=17590): Initializing Python.
[Thu Jun 21 16:07:48.521861 2018] [:info] [pid 17589] mod_wsgi (pid=17589): Attach interpreter ''.
[Thu Jun 21 16:07:48.522751 2018] [:info] [pid 17590] mod_wsgi (pid=17590): Attach interpreter ''.
[Thu Jun 21 16:07:49.426688 2018] [proxy:debug] [pid 17592] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.426741 2018] [proxy:debug] [pid 17592] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.426775 2018] [proxy:debug] [pid 17592] proxy_util.c(1936): AH00931: initialized single connection worker in child 17592 for (localhost)
[Thu Jun 21 16:07:49.426782 2018] [proxy:debug] [pid 17592] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.426788 2018] [proxy:debug] [pid 17592] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.426811 2018] [proxy:debug] [pid 17592] proxy_util.c(1936): AH00931: initialized single connection worker in child 17592 for (localhost)
[Thu Jun 21 16:07:49.426816 2018] [proxy:debug] [pid 17592] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.426821 2018] [proxy:debug] [pid 17592] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.426834 2018] [proxy:debug] [pid 17592] proxy_util.c(1936): AH00931: initialized single connection worker in child 17592 for (localhost)
[Thu Jun 21 16:07:49.426839 2018] [proxy:debug] [pid 17592] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.426845 2018] [proxy:debug] [pid 17592] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.426858 2018] [proxy:debug] [pid 17592] proxy_util.c(1936): AH00931: initialized single connection worker in child 17592 for (localhost)
[Thu Jun 21 16:07:49.426863 2018] [proxy:debug] [pid 17592] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.426869 2018] [proxy:debug] [pid 17592] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.426881 2018] [proxy:debug] [pid 17592] proxy_util.c(1936): AH00931: initialized single connection worker in child 17592 for (localhost)
[Thu Jun 21 16:07:49.426887 2018] [proxy:debug] [pid 17592] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:49.426889 2018] [proxy:debug] [pid 17592] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:49.426905 2018] [proxy:debug] [pid 17592] proxy_util.c(1936): AH00931: initialized single connection worker in child 17592 for (localhost)
[Thu Jun 21 16:07:49.426928 2018] [proxy:debug] [pid 17592] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:49.426931 2018] [proxy:debug] [pid 17592] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:49.426943 2018] [proxy:debug] [pid 17592] proxy_util.c(1936): AH00931: initialized single connection worker in child 17592 for (*)
[Thu Jun 21 16:07:49.427386 2018] [proxy:debug] [pid 17591] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.427426 2018] [proxy:debug] [pid 17591] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.427456 2018] [proxy:debug] [pid 17591] proxy_util.c(1936): AH00931: initialized single connection worker in child 17591 for (localhost)
[Thu Jun 21 16:07:49.427462 2018] [proxy:debug] [pid 17591] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.427467 2018] [proxy:debug] [pid 17591] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.427486 2018] [proxy:debug] [pid 17591] proxy_util.c(1936): AH00931: initialized single connection worker in child 17591 for (localhost)
[Thu Jun 21 16:07:49.427490 2018] [proxy:debug] [pid 17591] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.427496 2018] [proxy:debug] [pid 17591] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.427508 2018] [proxy:debug] [pid 17591] proxy_util.c(1936): AH00931: initialized single connection worker in child 17591 for (localhost)
[Thu Jun 21 16:07:49.427513 2018] [proxy:debug] [pid 17591] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.427521 2018] [proxy:debug] [pid 17591] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.427534 2018] [proxy:debug] [pid 17591] proxy_util.c(1936): AH00931: initialized single connection worker in child 17591 for (localhost)
[Thu Jun 21 16:07:49.427539 2018] [proxy:debug] [pid 17591] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.427544 2018] [proxy:debug] [pid 17591] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.427556 2018] [proxy:debug] [pid 17591] proxy_util.c(1936): AH00931: initialized single connection worker in child 17591 for (localhost)
[Thu Jun 21 16:07:49.427561 2018] [proxy:debug] [pid 17591] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:49.427564 2018] [proxy:debug] [pid 17591] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:49.427578 2018] [proxy:debug] [pid 17591] proxy_util.c(1936): AH00931: initialized single connection worker in child 17591 for (localhost)
[Thu Jun 21 16:07:49.427598 2018] [proxy:debug] [pid 17591] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:49.427602 2018] [proxy:debug] [pid 17591] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:49.427613 2018] [proxy:debug] [pid 17591] proxy_util.c(1936): AH00931: initialized single connection worker in child 17591 for (*)
[Thu Jun 21 16:07:49.437644 2018] [proxy:debug] [pid 17593] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.437685 2018] [proxy:debug] [pid 17593] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.437714 2018] [proxy:debug] [pid 17593] proxy_util.c(1936): AH00931: initialized single connection worker in child 17593 for (localhost)
[Thu Jun 21 16:07:49.437720 2018] [proxy:debug] [pid 17593] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.437726 2018] [proxy:debug] [pid 17593] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.437744 2018] [proxy:debug] [pid 17593] proxy_util.c(1936): AH00931: initialized single connection worker in child 17593 for (localhost)
[Thu Jun 21 16:07:49.437748 2018] [proxy:debug] [pid 17593] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.437755 2018] [proxy:debug] [pid 17593] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.437771 2018] [proxy:debug] [pid 17593] proxy_util.c(1936): AH00931: initialized single connection worker in child 17593 for (localhost)
[Thu Jun 21 16:07:49.437775 2018] [proxy:debug] [pid 17593] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.437781 2018] [proxy:debug] [pid 17593] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.437792 2018] [proxy:debug] [pid 17593] proxy_util.c(1936): AH00931: initialized single connection worker in child 17593 for (localhost)
[Thu Jun 21 16:07:49.437797 2018] [proxy:debug] [pid 17593] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.437802 2018] [proxy:debug] [pid 17593] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.437814 2018] [proxy:debug] [pid 17593] proxy_util.c(1936): AH00931: initialized single connection worker in child 17593 for (localhost)
[Thu Jun 21 16:07:49.437819 2018] [proxy:debug] [pid 17593] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:49.437821 2018] [proxy:debug] [pid 17593] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:49.437839 2018] [proxy:debug] [pid 17593] proxy_util.c(1936): AH00931: initialized single connection worker in child 17593 for (localhost)
[Thu Jun 21 16:07:49.437859 2018] [proxy:debug] [pid 17593] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:49.437862 2018] [proxy:debug] [pid 17593] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:49.437874 2018] [proxy:debug] [pid 17593] proxy_util.c(1936): AH00931: initialized single connection worker in child 17593 for (*)
[Thu Jun 21 16:07:49.448564 2018] [proxy:debug] [pid 17594] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.448606 2018] [proxy:debug] [pid 17594] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.448639 2018] [proxy:debug] [pid 17594] proxy_util.c(1936): AH00931: initialized single connection worker in child 17594 for (localhost)
[Thu Jun 21 16:07:49.448645 2018] [proxy:debug] [pid 17594] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.448651 2018] [proxy:debug] [pid 17594] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.448669 2018] [proxy:debug] [pid 17594] proxy_util.c(1936): AH00931: initialized single connection worker in child 17594 for (localhost)
[Thu Jun 21 16:07:49.448673 2018] [proxy:debug] [pid 17594] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.448680 2018] [proxy:debug] [pid 17594] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.448692 2018] [proxy:debug] [pid 17594] proxy_util.c(1936): AH00931: initialized single connection worker in child 17594 for (localhost)
[Thu Jun 21 16:07:49.448697 2018] [proxy:debug] [pid 17594] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.448702 2018] [proxy:debug] [pid 17594] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.448714 2018] [proxy:debug] [pid 17594] proxy_util.c(1936): AH00931: initialized single connection worker in child 17594 for (localhost)
[Thu Jun 21 16:07:49.448719 2018] [proxy:debug] [pid 17594] proxy_util.c(1843): AH00925: initializing worker ajp://localhost shared
[Thu Jun 21 16:07:49.448724 2018] [proxy:debug] [pid 17594] proxy_util.c(1885): AH00927: initializing worker ajp://localhost local
[Thu Jun 21 16:07:49.448736 2018] [proxy:debug] [pid 17594] proxy_util.c(1936): AH00931: initialized single connection worker in child 17594 for (localhost)
[Thu Jun 21 16:07:49.448741 2018] [proxy:debug] [pid 17594] proxy_util.c(1843): AH00925: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ shared
[Thu Jun 21 16:07:49.448748 2018] [proxy:debug] [pid 17594] proxy_util.c(1885): AH00927: initializing worker unix:/run/httpd/ipa-custodia.sock|http://localhost/keys/ local
[Thu Jun 21 16:07:49.448763 2018] [proxy:debug] [pid 17594] proxy_util.c(1936): AH00931: initialized single connection worker in child 17594 for (localhost)
[Thu Jun 21 16:07:49.448784 2018] [proxy:debug] [pid 17594] proxy_util.c(1843): AH00925: initializing worker proxy:reverse shared
[Thu Jun 21 16:07:49.448787 2018] [proxy:debug] [pid 17594] proxy_util.c(1885): AH00927: initializing worker proxy:reverse local
[Thu Jun 21 16:07:49.448799 2018] [proxy:debug] [pid 17594] proxy_util.c(1936): AH00931: initialized single connection worker in child 17594 for (*)
[Thu Jun 21 16:07:49.513481 2018] [:debug] [pid 17592] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:49.513520 2018] [:info] [pid 17592] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:49.517733 2018] [:info] [pid 17592] mod_wsgi (pid=17592): Initializing Python.
[Thu Jun 21 16:07:49.526428 2018] [:info] [pid 17592] mod_wsgi (pid=17592): Attach interpreter ''.
[Thu Jun 21 16:07:49.580304 2018] [:debug] [pid 17591] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:49.580343 2018] [:info] [pid 17591] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:49.584594 2018] [:info] [pid 17591] mod_wsgi (pid=17591): Initializing Python.
[Thu Jun 21 16:07:49.593248 2018] [:info] [pid 17591] mod_wsgi (pid=17591): Attach interpreter ''.
[Thu Jun 21 16:07:49.612767 2018] [:debug] [pid 17593] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:49.612805 2018] [:info] [pid 17593] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:49.615224 2018] [:debug] [pid 17594] nss_engine_init.c(428): SNI is enabled
[Thu Jun 21 16:07:49.615252 2018] [:info] [pid 17594] Init: Seeding PRNG with 144 bytes of entropy
[Thu Jun 21 16:07:49.617021 2018] [:info] [pid 17593] mod_wsgi (pid=17593): Initializing Python.
[Thu Jun 21 16:07:49.619412 2018] [:info] [pid 17594] mod_wsgi (pid=17594): Initializing Python.
[Thu Jun 21 16:07:49.625699 2018] [:info] [pid 17593] mod_wsgi (pid=17593): Attach interpreter ''.
[Thu Jun 21 16:07:49.627809 2018] [:info] [pid 17594] mod_wsgi (pid=17594): Attach interpreter ''.
[Thu Jun 21 16:07:49.692088 2018] [auth_gssapi:error] [pid 17520] [client 38.99.100.6:58782] NO AUTH DATA Client did not send any authentication headers, referer: https://example.ipa.EXAMPLE.com/ipa/ui/
[Thu Jun 21 16:07:55.856150 2018] [:error] [pid 17515] ipa: DEBUG: WSGI wsgi_dispatch.__call__:
[Thu Jun 21 16:07:55.856241 2018] [:error] [pid 17515] ipa: DEBUG: WSGI login_password.__call__:
[Thu Jun 21 16:07:55.856961 2018] [:error] [pid 17515] ipa: DEBUG: Obtaining armor in ccache /var/run/ipa/ccaches/armor_17515
[Thu Jun 21 16:07:55.857026 2018] [:error] [pid 17515] ipa: DEBUG: Initializing anonymous ccache
[Thu Jun 21 16:07:55.857118 2018] [:error] [pid 17515] ipa: DEBUG: Starting external process
[Thu Jun 21 16:07:55.857159 2018] [:error] [pid 17515] ipa: DEBUG: args=/usr/bin/kinit -n -c /var/run/ipa/ccaches/armor_17515 -X X509_anchors=FILE:/var/kerberos/krb5kdc/kdc.crt -X X509_anchors=FILE:/var/lib/ipa-client/pki/kdc-ca-bundle.pem
[Thu Jun 21 16:07:55.942883 2018] [:error] [pid 17515] ipa: DEBUG: Process finished, return code=0
[Thu Jun 21 16:07:55.942962 2018] [:error] [pid 17515] ipa: DEBUG: stdout=
[Thu Jun 21 16:07:55.943013 2018] [:error] [pid 17515] ipa: DEBUG: stderr=
[Thu Jun 21 16:07:55.943191 2018] [:error] [pid 17515] ipa: DEBUG: Initializing principal plastikman using password
[Thu Jun 21 16:07:55.943250 2018] [:error] [pid 17515] ipa: DEBUG: Using armor ccache /var/run/ipa/ccaches/armor_17515 for FAST webauth
[Thu Jun 21 16:07:55.943290 2018] [:error] [pid 17515] ipa: DEBUG: Using enterprise principal
[Thu Jun 21 16:07:55.943402 2018] [:error] [pid 17515] ipa: DEBUG: Starting external process
[Thu Jun 21 16:07:55.943447 2018] [:error] [pid 17515] ipa: DEBUG: args=/usr/bin/kinit plastikman -c /var/run/ipa/ccaches/kinit_17515 -T /var/run/ipa/ccaches/armor_17515 -E
[Thu Jun 21 16:07:55.990352 2018] [:error] [pid 17515] ipa: DEBUG: Process finished, return code=0
[Thu Jun 21 16:07:55.990445 2018] [:error] [pid 17515] ipa: DEBUG: stdout=Password for plastikman@IPA.EXAMPLE.COM:
[Thu Jun 21 16:07:55.990451 2018] [:error] [pid 17515]
[Thu Jun 21 16:07:55.990492 2018] [:error] [pid 17515] ipa: DEBUG: stderr=
[Thu Jun 21 16:07:55.990582 2018] [:error] [pid 17515] ipa: DEBUG: Cleanup the armor ccache
[Thu Jun 21 16:07:55.990682 2018] [:error] [pid 17515] ipa: DEBUG: Starting external process
[Thu Jun 21 16:07:55.990724 2018] [:error] [pid 17515] ipa: DEBUG: args=/usr/bin/kdestroy -A -c /var/run/ipa/ccaches/armor_17515
[Thu Jun 21 16:07:55.996581 2018] [:error] [pid 17515] ipa: DEBUG: Process finished, return code=0
[Thu Jun 21 16:07:55.996652 2018] [:error] [pid 17515] ipa: DEBUG: stdout=
[Thu Jun 21 16:07:55.996696 2018] [:error] [pid 17515] ipa: DEBUG: stderr=
[Thu Jun 21 16:07:56.009529 2018] [core:debug] [pid 17589] util_cookies.c(129): [client 172.31.28.149:42910] AH00009: ap_cookie: user '(null)' removed cookie: 'ipa_session=;Max-Age=0;path=/ipa;httponly;secure;'
[Thu Jun 21 16:07:56.009547 2018] [headers:debug] [pid 17589] mod_headers.c(848): AH01503: headers: ap_headers_error_filter()
[Thu Jun 21 16:07:56.012119 2018] [:error] [pid 17515] ipa: INFO: 401 Unauthorized: [Errno 13] Permission denied
[Thu Jun 21 16:08:20.460227 2018] [:info] [pid 17592] mod_wsgi (pid=17592): Destroying interpreters.
[Thu Jun 21 16:08:20.460241 2018] [:info] [pid 17592] mod_wsgi (pid=17592): Cleanup interpreter ''.
[Thu Jun 21 16:08:20.463563 2018] [:info] [pid 17592] mod_wsgi (pid=17592): Terminating Python.
[Thu Jun 21 16:08:20.465153 2018] [:info] [pid 17592] mod_wsgi (pid=17592): Python has shutdown.
[Thu Jun 21 16:08:26.467102 2018] [:info] [pid 17591] mod_wsgi (pid=17591): Destroying interpreters.
[Thu Jun 21 16:08:26.467115 2018] [:info] [pid 17591] mod_wsgi (pid=17591): Cleanup interpreter ''.
[Thu Jun 21 16:08:26.470351 2018] [:info] [pid 17591] mod_wsgi (pid=17591): Terminating Python.
[Thu Jun 21 16:08:26.471989 2018] [:info] [pid 17591] mod_wsgi (pid=17591): Python has shutdown.

Metadata Update from @cheimes:
- Issue assigned to cheimes

5 years ago

Metadata Update from @cheimes:
- Issue priority set to: important
- Issue set to the milestone: FreeIPA 4.5.5

5 years ago

Metadata Update from @cheimes:
- Custom field on_review adjusted to https://github.com/freeipa/freeipa/pull/2057

5 years ago

master:

  • ba8cbb8 Ensure that public cert and CA bundle are readable
  • 1434f2a Always make ipa.p11-kit world-readable
  • 89b2137 Make /etc/httpd/alias world readable & executable
  • c2eb0f1 Fix permission of public files in upgrader

ipa-4-6:

  • fc63ad8 Ensure that public cert and CA bundle are readable
  • 9576e3c Always make ipa.p11-kit world-readable
  • b76b50d Make /etc/httpd/alias world readable & executable
  • 8164fba Fix permission of public files in upgrader

Metadata Update from @cheimes:
- Issue close_status updated to: fixed
- Issue status updated to: Closed (was: Open)

5 years ago

Metadata Update from @frenaud:
- Custom field rhbz adjusted to https://bugzilla.redhat.com/show_bug.cgi?id=1630361

5 years ago

Login to comment on this ticket.

Metadata