Cinder unittest failures have deep stracebacks due to osprofiler
Affects | Status | Importance | Assigned to | Milestone | |
---|---|---|---|---|---|
Cinder |
Fix Released
|
Undecided
|
Unassigned | ||
osprofiler |
Opinion
|
Undecided
|
Unassigned |
Bug Description
Logs come from http://
When cinder unittests fail they can have very deep stracebacks with a large portion of the traceback being nested osprofiler calls. I think there are possibly two bugs here.
First why is osprofiler enabled when running unittests? Shouldn't we be testing non profiled code with an optional opt in for profiling to do debugging? Mostly thinking that this osprofiler output is noise for typical debug cases and is really only needed when you want that profiling to be done.
Second would it be possible to have osprofiler better annotate its wrapping so that we can see what has been wrapped and where calls are made when we get the stacktraces? as is its a bit of a mess.
Changed in cinder: | |
assignee: | nobody → Boris Pavlovic (boris-42) |
Changed in cinder: | |
assignee: | Boris Pavlovic (boris-42) → Dina Belova (dbelova) |
I intended on pasting the logs here for historical reasons and failed. Here they are:
2015-04-22 15:27:20.570 | Captured stderr: jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ eventlet/ hubs/hub. py", line 457, in fire_timers jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ eventlet/ hubs/timer. py", line 58, in __call__ jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ eventlet/ greenthread. py", line 214, in main jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ osprofiler/ profiler. py", line 105, in wrapper jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ osprofiler/ profiler. py", line 105, in wrapper jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ osprofiler/ profiler. py", line 105, in wrapper jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ osprofiler/ profiler. py", line 105, in wrapper jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ osprofiler/ profiler. py", line 105, in wrapper jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ osprofiler/ profiler. py", line 105, in wrapper jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ osprofiler/ profiler. py", line 105, in wrapper jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ osprofiler/ profiler. py", line 105, in wrapper jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ osprofiler/ profiler. py", line 105, in wrapper jenkins/ workspace/ gate-cinder- python27/ .tox/py27/ local/lib/ python2. 7/site- packages/ osprofiler/ profiler. py", line 105, in wrapper
2015-04-22 15:27:20.570 | ~~~~~~~~~~~~~~~~
2015-04-22 15:27:20.571 | Traceback (most recent call last):
2015-04-22 15:27:20.571 | File "/home/
2015-04-22 15:27:20.571 | timer()
2015-04-22 15:27:20.571 | File "/home/
2015-04-22 15:27:20.571 | cb(*args, **kw)
2015-04-22 15:27:20.571 | File "/home/
2015-04-22 15:27:20.571 | result = function(*args, **kwargs)
2015-04-22 15:27:20.571 | File "/home/
2015-04-22 15:27:20.571 | return f(*args, **kwargs)
2015-04-22 15:27:20.571 | File "/home/
2015-04-22 15:27:20.572 | return f(*args, **kwargs)
2015-04-22 15:27:20.572 | File "/home/
2015-04-22 15:27:20.572 | return f(*args, **kwargs)
2015-04-22 15:27:20.572 | File "/home/
2015-04-22 15:27:20.572 | return f(*args, **kwargs)
2015-04-22 15:27:20.572 | File "/home/
2015-04-22 15:27:20.572 | return f(*args, **kwargs)
2015-04-22 15:27:20.572 | File "/home/
2015-04-22 15:27:20.572 | return f(*args, **kwargs)
2015-04-22 15:27:20.572 | File "/home/
2015-04-22 15:27:20.572 | return f(*args, **kwargs)
2015-04-22 15:27:20.573 | File "/home/
2015-04-22 15:27:20.573 | return f(*args, **kwargs)
2015-04-22 15:27:20.573 | File "/home/
2015-04-22 15:27:20.573 | return f(*args, **kwargs)
2015-04-22 15:27:20.573 | File "/home/
2015-04-22 1...