#9700 mod_wsgi context and logging from IPA components
Opened by abbra. Modified

Azure CI reports a lot of issues like this:

[Fri Nov 15 12:45:33.034684 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] Exception ignored in: <_io.TextIOWrapper name='<wsgi.errors>' encoding='utf-8'>
[Fri Nov 15 12:45:33.034708 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] Traceback (most recent call last):
[Fri Nov 15 12:45:33.034717 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616]   File "/usr/lib/python3.13/site-packages/ipapython/dn.py", line 544, in <genexpr>
[Fri Nov 15 12:45:33.035035 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616]     return (len(rdn),) + tuple(ava_key(k) for k in rdn)
[Fri Nov 15 12:45:33.035050 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] RuntimeError: log object has expired
[Fri Nov 15 12:45:33.036168 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] Exception ignored in: <_io.TextIOWrapper name='<wsgi.errors>' encoding='utf-8'>
[Fri Nov 15 12:45:33.036183 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] Traceback (most recent call last):
[Fri Nov 15 12:45:33.036191 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616]   File "/usr/lib/python3.13/site-packages/ipapython/dn.py", line 544, in <genexpr>
[Fri Nov 15 12:45:33.036726 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616]     return (len(rdn),) + tuple(ava_key(k) for k in rdn)
[Fri Nov 15 12:45:33.036741 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] RuntimeError: log object has expired
[Fri Nov 15 12:45:33.036848 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] Exception ignored in: <_io.TextIOWrapper name='<wsgi.errors>' encoding='utf-8'>
[Fri Nov 15 12:45:33.036859 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] Traceback (most recent call last):
[Fri Nov 15 12:45:33.036866 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616]   File "/usr/lib/python3.13/site-packages/ipapython/dn.py", line 544, in <genexpr>
[Fri Nov 15 12:45:33.037135 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616]     return (len(rdn),) + tuple(ava_key(k) for k in rdn)
[Fri Nov 15 12:45:33.037148 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] RuntimeError: log object has expired
[Fri Nov 15 12:45:33.038463 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] Exception ignored in: <_io.TextIOWrapper name='<wsgi.errors>' encoding='utf-8'>
[Fri Nov 15 12:45:33.038479 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] Traceback (most recent call last):
[Fri Nov 15 12:45:33.038487 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616]   File "/usr/lib/python3.13/site-packages/ipapython/dn.py", line 544, in <genexpr>
[Fri Nov 15 12:45:33.038868 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616]     return (len(rdn),) + tuple(ava_key(k) for k in rdn)
[Fri Nov 15 12:45:33.038883 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] RuntimeError: log object has expired
[Fri Nov 15 12:45:33.042551 2024] [wsgi:error] [pid 11628:tid 11639] [remote 2001:db8:1:2::2:57616] ipa: INFO: [jsonserver_session] admin@IPA.TEST: service_del/1(['SERVICE/krb-host.ipa.test@IPA.TEST'], version='2.254'): NotFound

or this:

[Fri Nov 15 12:45:33.698554 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] Exception ignored in: <_io.TextIOWrapper name='<wsgi.errors>' encoding='utf-8'>
[Fri Nov 15 12:45:33.698582 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] Traceback (most recent call last):
[Fri Nov 15 12:45:33.698593 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664]   File "/usr/lib64/python3.13/site-packages/ldap/ldapobject.py", line 128, in _ldap_call
[Fri Nov 15 12:45:33.698930 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664]     result = func(*args,**kwargs)
[Fri Nov 15 12:45:33.698946 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] RuntimeError: log object has expired
[Fri Nov 15 12:45:33.699031 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] Exception ignored in: <_io.TextIOWrapper name='<wsgi.errors>' encoding='utf-8'>
[Fri Nov 15 12:45:33.699043 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] Traceback (most recent call last):
[Fri Nov 15 12:45:33.699051 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664]   File "/usr/lib64/python3.13/site-packages/ldap/ldapobject.py", line 128, in _ldap_call
[Fri Nov 15 12:45:33.699164 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664]     result = func(*args,**kwargs)
[Fri Nov 15 12:45:33.699195 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] RuntimeError: log object has expired
[Fri Nov 15 12:45:33.700529 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] Exception ignored in: <_io.TextIOWrapper name='<wsgi.errors>' encoding='utf-8'>
[Fri Nov 15 12:45:33.700544 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] Traceback (most recent call last):
[Fri Nov 15 12:45:33.700553 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664]   File "/usr/lib64/python3.13/site-packages/ldap/ldapobject.py", line 128, in _ldap_call
[Fri Nov 15 12:45:33.700706 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664]     result = func(*args,**kwargs)
[Fri Nov 15 12:45:33.700720 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] RuntimeError: log object has expired
[Fri Nov 15 12:45:33.700857 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] Exception ignored in: <_io.TextIOWrapper name='<wsgi.errors>' encoding='utf-8'>
[Fri Nov 15 12:45:33.700890 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] Traceback (most recent call last):
[Fri Nov 15 12:45:33.700919 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664]   File "/usr/lib64/python3.13/site-packages/ldap/ldapobject.py", line 128, in _ldap_call
[Fri Nov 15 12:45:33.701052 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664]     result = func(*args,**kwargs)
[Fri Nov 15 12:45:33.701065 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] RuntimeError: log object has expired
[Fri Nov 15 12:45:33.701144 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] Exception ignored in: <_io.TextIOWrapper name='<wsgi.errors>' encoding='utf-8'>
[Fri Nov 15 12:45:33.701155 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] Traceback (most recent call last):
[Fri Nov 15 12:45:33.701175 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664]   File "/usr/lib64/python3.13/site-packages/ldap/ldapobject.py", line 128, in _ldap_call
[Fri Nov 15 12:45:33.701287 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664]     result = func(*args,**kwargs)
[Fri Nov 15 12:45:33.701315 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] RuntimeError: log object has expired
[Fri Nov 15 12:45:33.768710 2024] [wsgi:error] [pid 11616:tid 11625] [remote 2001:db8:1:2::2:57664] ipa: INFO: [jsonserver_session] admin@IPA.TEST: service_del/1(['SERVICE/krb-host.ipa.test@IPA.TEST'], version='2.254'): SUCCESS

or this:

[Fri Nov 15 12:30:52.751011 2024] [wsgi:error] [pid 8871:tid 9187] [remote 2001:db8:1:2::2:50048] ipa: INFO: [jsonserver_session] admin@IPA.TEST: dns_is_enabled/1(version='2.254'): SUCCESS
[Fri Nov 15 12:30:52.751941 2024] [wsgi:error] [pid 8871:tid 9187] Exception ignored in: <_io.TextIOWrapper name='<wsgi.errors>' encoding='utf-8'>
[Fri Nov 15 12:30:52.751963 2024] [wsgi:error] [pid 8871:tid 9187] RuntimeError: log object has expired
[Fri Nov 15 12:30:52.997906 2024] [:warn] [pid 9121:tid 9171] [client 2001:db8:1:2::2:50048] failed to set perms (3140) on file (/run/ipa/ccaches/admin@IPA.TEST-GS2vOo)!, referer: https://master1.ipa.test/ipa/xml
[Fri Nov 15 12:30:53.261727 2024] [wsgi:error] [pid 8868:tid 9193] [remote 2001:db8:1:2::2:50048] ipa: INFO: [jsonserver_session] admin@IPA.TEST: idrange_find/1(None, version='2.254'): SUCCESS

Relevant: https://github.com/GrahamDumpleton/mod_wsgi/issues/912

Metadata