Yubico Forum ...visit our web-store at 2014-09-26T20:09:07+01:00 https://forum.yubico.com/feed.php?f=31&t=1399 2014-09-26T20:09:07+01:00 2014-09-26T20:09:07+01:00 https://forum.yubico.com/viewtopic.php?t=1399&p=5596#p5596 <![CDATA[Re: [Q?] sporadic '500 Internal Server Error' failures - Yub]]>
r.

Statistics: Posted by jerichod505 — Fri Sep 26, 2014 8:09 pm

]]> 2014-09-23T15:21:44+01:00 2014-09-23T15:21:44+01:00 https://forum.yubico.com/viewtopic.php?t=1399&p=5568#p5568 <![CDATA[Re: [Q?] sporadic '500 Internal Server Error' failures - Yub]]> Statistics: Posted by dain — Tue Sep 23, 2014 3:21 pm

]]> 2014-09-23T14:59:08+01:00 2014-09-23T14:59:08+01:00 https://forum.yubico.com/viewtopic.php?t=1399&p=5566#p5566 <![CDATA[Re: [Q?] sporadic '500 Internal Server Error' failures - Yub]]> Statistics: Posted by lshiry — Tue Sep 23, 2014 2:59 pm

]]> 2014-06-18T14:32:26+01:00 2014-06-18T14:32:26+01:00 https://forum.yubico.com/viewtopic.php?t=1399&p=5345#p5345 <![CDATA[Re: [Q?] sporadic '500 Internal Server Error' failures - Yub]]> Statistics: Posted by lshiry — Wed Jun 18, 2014 2:32 pm

]]> 2014-06-10T00:08:09+01:00 2014-06-10T00:08:09+01:00 https://forum.yubico.com/viewtopic.php?t=1399&p=5308#p5308 <![CDATA[[Q?] sporadic '500 Internal Server Error' failures - YubiX]]>
I searched the forum and found a similar error but not for the reasons I think are happening. In my case simply restarting the freeRADIUS server fixes the issue.

After lots of debugging, it appears that the problem is related to the /usr/share/pyshared/yubiauth/client/rest.py attempting to access the database. the only real clue is the following errors in the Apache log:

[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] mod_wsgi (pid=5449): Exception occurred processing WSGI script '/usr/share/pyshared/yubiauth/client/rest.py'.
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] Traceback (most recent call last):
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/lib/python2.7/dist-packages/webob/dec.py", line 147, in __call__
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] resp = self.call_func(req, *args, **self.kwargs)
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/lib/python2.7/dist-packages/webob/dec.py", line 210, in call_func
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] return self.func(req, *args, **kwargs)
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/share/pyshared/yubiauth/client/rest.py", line 66, in ClientMiddleware
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] response = request.get_response(app)
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/lib/python2.7/dist-packages/yubiauth/util/controller.py", line 52, in __exit__
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] self.commit()
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/lib/python2.7/dist-packages/yubiauth/util/controller.py", line 63, in commit
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] self.session.commit()
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/lib/python2.7/dist-packages/sqlalchemy/orm/session.py", line 645, in commit
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] self.transaction.commit()
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/lib/python2.7/dist-packages/sqlalchemy/orm/session.py", line 317, in commit
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] t[1].commit()
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 1977, in commit
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] self._do_commit()
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 2007, in _do_commit
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] self.connection._commit_impl()
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 1233, in _commit_impl
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] self.engine.dialect.do_commit(self.connection)
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 1012, in connection
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] return self._revalidate_connection()
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] File "/usr/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 1018, in _revalidate_connection
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] "Can't reconnect until invalid "
[Thu Jun 05 13:56:08 2014] [error] [client 127.0.0.1] InvalidRequestError: Can't reconnect until invalid transaction is rolled back

i am not quite sure if this is part of the symptoms or the cause, and with all the calls in and out of perl/freeradius/python i am not sure where this error is turning into the 'internal server error' message. one thing for sure is that debugging this tangled stuff is beyond me.

Anybody else seen these issues?

thanks.
jd505

Statistics: Posted by jerichod505 — Tue Jun 10, 2014 12:08 am

]]>