Note: This bug is displayed in read-only format because the product is no longer active in Red Hat Bugzilla.

Bug 1110460

Summary:	[BEAKER] ProtocolError for beaker.en gineering.redhat.com/client/: 500 INTERNAL SERVER ERROR
Product:	[Retired] Beaker	Reporter:	PaulB <pbunyan>
Component:	beah	Assignee:	beaker-dev-list
Status:	CLOSED DUPLICATE	QA Contact:	tools-bugs <tools-bugs>
Severity:	unspecified	Docs Contact:
Priority:	unspecified
Version:	0.16	CC:	aigao, asaha, bpeck, dcallagh, jburke, jstancek, pbunyan, rmancy
Target Milestone:	---
Target Release:	---
Hardware:	Unspecified
OS:	Unspecified
Whiteboard:
Fixed In Version:		Doc Type:	Bug Fix
Doc Text:		Story Points:	---
Clone Of:		Environment:
Last Closed:	2014-06-19 00:46:06 UTC	Type:	Bug
Regression:	---	Mount Type:	---
Documentation:	---	CRM:
Verified Versions:		Category:	---
oVirt Team:	---	RHEL 7.3 requirements from Atomic Host:
Cloudforms Team:	---	Target Upstream Version:
Embargoed:

Comment 2 PaulB 2014-06-18 12:03:11 UTC

All,
Issue seen here:
https://beaker.engineering.redhat.com/recipes/1399306
http://beaker-archive.app.eng.bos.redhat.com/beaker-logs/2014/06/6731/673107/1399306/console.log
-----<-snip->-----
2014-06-17 07:05:21,184 backend _send_cmd: INFO Command ['Command', 'forward', 'ef3a1531-7221-4970-a026-a6c59875c554', {'event': ['Event', 'extend_watchdog', 'ef1352b1-8d87-4ee6-a78e-91cccb969918', {'source': 'beah_beaker_backend', 'id': '67350718-34f8-45c9-955a-bc46774cee1d'}, None, {'timeout': 2994}]}] sent.  
2014-06-17 07:05:21,273 rhts_task checkin_start: INFO setting nohup  
06/17/14 07:05:21  testID:21984698 start:  
/mnt/tests/distribution/install /mnt/tests/distribution/install  
logger: /usr/bin/rhts-test-runner.sh report_finish start...  
jobsl = [1]+ 1730 Done make run >> /mnt/testarea/TESTOUT.log 2>&1  
status = Done  
no machines specified. exiting  
2014-06-17 07:05:42,698 rhts_task checkin_finish: INFO resetting nohup  
06/17/14 07:05:42  testID:21984698 finish:  
Nothing to restore.  
logger: /usr/bin/rhts-test-runner.sh report_finish stop...  
+ rc=0  
+ '[' -n '' ']'  
+ exit 0  
2014-06-17 07:05:42,954 rhts_task task_exited: INFO task_exited([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended by signal 15.  
])  
2014-06-17 07:05:42,957 rhts_task on_exit: INFO quitting...  
2014-06-17 07:05:43,964 rhts_task task_ended: INFO task_ended([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended by signal 15.  
])  
2014-06-17 07:05:44,051 beah processExited: INFO TaskStdoutProtocol:processExited([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessDone'>: A process has ended without apparent errors: process finished with exit code 0.  
])  
2014-06-17 07:05:44,052 beah processEnded: INFO TaskStdoutProtocol:processEnded([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessDone'>: A process has ended without apparent errors: process finished with exit code 0.  
])  
2014-06-17 07:05:44,060 beah task_finished: INFO Task 67350718-34f8-45c9-955a-bc46774cee1d has finished.  
2014-06-17 07:05:44,066 backend async_proc: INFO Task 21984698 done. Submitting logs...  
2014-06-17 07:05:56,899 backend _send_cmd: INFO Command ['Command', 'forward', '0653c6c5-5d9c-4d34-8bc7-2add443448e2', {'event': ['Event', 'extend_watchdog', '82774a10-48a1-43ca-96ac-6ccaf5a9b266', {'source': 'beah_beaker_backend', 'id': '67350718-34f8-45c9-955a-bc46774cee1d'}, None, {'timeout': 14394}]}] sent.  
2014-06-17 07:07:46,787 backend simple_recipe: ERROR Encoutnered problem while running task '21984698'.  
Traceback (most recent call last):  
  File "/usr/lib/python2.6/site-packages/beah/backends/beakerlc.py", line 518, in simple_recipe  
    result = task.getResult()  
  File "/usr/lib/python2.6/site-packages/twisted/internet/defer.py", line 584, in getResult  
    self.result.raiseException()  
  File "/usr/lib/python2.6/site-packages/twisted/internet/defer.py", line 605, in _deferGenerator  
    result = g.next()  
  File "/usr/lib/python2.6/site-packages/beah/backends/beakerlc.py", line 599, in _run_task  
    thingy.getResult()  
  File "/usr/lib/python2.6/site-packages/twisted/internet/defer.py", line 584, in getResult  
    self.result.raiseException()  
  File "/usr/lib/python2.6/site-packages/twisted/web/xmlrpc.py", line 307, in parseResponse  
    response = xmlrpclib.loads(contents)[0][0]  
  File "/usr/lib/python2.6/xmlrpclib.py", line 1184, in loads  
    return u.close(), u.getmethodname()  
  File "/usr/lib/python2.6/xmlrpclib.py", line 838, in close  
    raise Fault(**self._stack[0])  
Fault: <Fault 1: "<class 'xmlrpclib.ProtocolError'>:<ProtocolError for beaker.engineering.redhat.com/client/: 500 INTERNAL SERVER ERROR>">
-----<-snip->-----

Best,
-pbunyan

Comment 3 Dan Callaghan 2014-06-19 00:46:06 UTC

Jun 17 11:07:46 beaker-02 beaker-server[978]: bkr.server ERROR Exception on /client/ [POST]
Jun 17 11:07:46 beaker-02 beaker-server[978]:  Traceback (most recent call last):
[...]
Jun 17 11:07:46 beaker-02 beaker-server[978]:  OperationalError: (OperationalError) (1205, 'Lock wait timeout exceeded; try restarting transaction') 'UPDATE job SET dirty_version=%s WHERE job.id = %s' ('\x96\xaeL\xd3\xa2lO\x1d\xa9L\xcb\xf3\xd6\x1e\x97\x89', 673107L)

This is the same "Lock wait timeout exceeded" error from bug 1097861.

*** This bug has been marked as a duplicate of bug 1097861 ***