New submission from Xiang Zhang:
Recently when I run the test suite test_cprofile always produces some verbose
info but won't fail. This is not the case before. I am not sure this is a bug
or not. git bisect tells me
https://github.com/python/cpython/commit/5566bbb8d563646d83e8172410fa0c085e8233b1
is responsible. I also see the verbose info on some buildbots.
[cpython]$ ./python -m test test_cprofile
Run tests sequentially
0:00:00 [1/1] test_cprofile
Stats.print_callers output for Profile doesn't fit expectation!
---
+++
@@ -1,15 +1,16 @@
-profilee.py:110(__getattr__) <- 16 0.016 0.016
profilee.py:98(subhelper)
-profilee.py:25(testfunc) <- 1 0.270 1.000
<string>:1(<module>)
-profilee.py:35(factorial) <- 1 0.014 0.130
profilee.py:25(testfunc)
- 20/3 0.130 0.147
profilee.py:35(factorial)
- 2 0.006 0.040
profilee.py:84(helper2_indirect)
-profilee.py:48(mul) <- 20 0.020 0.020
profilee.py:35(factorial)
-profilee.py:55(helper) <- 2 0.040 0.600
profilee.py:25(testfunc)
-profilee.py:73(helper1) <- 4 0.116 0.120
profilee.py:55(helper)
-profilee.py:84(helper2_indirect) <- 2 0.000 0.140
profilee.py:55(helper)
-profilee.py:88(helper2) <- 6 0.234 0.300
profilee.py:55(helper)
- 2 0.078 0.100
profilee.py:84(helper2_indirect)
-profilee.py:98(subhelper) <- 8 0.064 0.080
profilee.py:88(helper2)
-{built-in method builtins.hasattr} <- 4 0.000 0.004
profilee.py:73(helper1)
- 8 0.000 0.008
profilee.py:88(helper2)
-{built-in method sys.exc_info} <- 4 0.000 0.000
profilee.py:73(helper1)
+profilee.py:110(__getattr__) <- 16 0.016 0.016
profilee.py:98(subhelper)
+profilee.py:25(testfunc) <- 1 0.270 1.000
<string>:1(<module>)
+profilee.py:35(factorial) <- 1 0.014 0.130
profilee.py:25(testfunc)
+ 20/3 0.130 0.147
profilee.py:35(factorial)
+ 2 0.006 0.040
profilee.py:84(helper2_indirect)
+profilee.py:48(mul) <- 20 0.020 0.020
profilee.py:35(factorial)
+profilee.py:55(helper) <- 2 0.040 0.600
profilee.py:25(testfunc)
+profilee.py:73(helper1) <- 4 0.116 0.120
profilee.py:55(helper)
+profilee.py:84(helper2_indirect) <- 2 0.000 0.140
profilee.py:55(helper)
+profilee.py:88(helper2) <- 6 0.234 0.300
profilee.py:55(helper)
+ 2 0.078 0.100
profilee.py:84(helper2_indirect)
+profilee.py:98(subhelper) <- 8 0.064 0.080
profilee.py:88(helper2)
+{built-in method builtins.hasattr} <- 4 0.000 0.004
profilee.py:73(helper1)
+ 8 0.000 0.008
profilee.py:88(helper2)
+{built-in method sys.exc_info} <- 4 0.000 0.000
profilee.py:73(helper1)
+{method 'append' of 'list' objects} <- 4 0.000 0.000
profilee.py:73(helper1)
Stats.print_callees output for Profile doesn't fit expectation!
---
+++
@@ -1,16 +1,16 @@
-<string>:1(<module>) -> 1 0.270 1.000
profilee.py:25(testfunc)
-profilee.py:110(__getattr__) ->
-profilee.py:25(testfunc) -> 1 0.014 0.130
profilee.py:35(factorial)
- 2 0.040 0.600
profilee.py:55(helper)
-profilee.py:35(factorial) -> 20/3 0.130 0.147
profilee.py:35(factorial)
- 20 0.020 0.020
profilee.py:48(mul)
-profilee.py:48(mul) ->
-profilee.py:55(helper) -> 4 0.116 0.120
profilee.py:73(helper1)
- 2 0.000 0.140
profilee.py:84(helper2_indirect)
- 6 0.234 0.300
profilee.py:88(helper2)
-profilee.py:73(helper1) -> 4 0.000 0.004 {built-in
method builtins.hasattr}
-profilee.py:84(helper2_indirect) -> 2 0.006 0.040
profilee.py:35(factorial)
- 2 0.078 0.100
profilee.py:88(helper2)
-profilee.py:88(helper2) -> 8 0.064 0.080
profilee.py:98(subhelper)
-profilee.py:98(subhelper) -> 16 0.016 0.016
profilee.py:110(__getattr__)
-{built-in method builtins.hasattr} -> 12 0.012 0.012
profilee.py:110(__getattr__)
+<string>:1(<module>) -> 1 0.270 1.000
profilee.py:25(testfunc)
+profilee.py:110(__getattr__) ->
+profilee.py:25(testfunc) -> 1 0.014 0.130
profilee.py:35(factorial)
+ 2 0.040 0.600
profilee.py:55(helper)
+profilee.py:35(factorial) -> 20/3 0.130 0.147
profilee.py:35(factorial)
+ 20 0.020 0.020
profilee.py:48(mul)
+profilee.py:48(mul) ->
+profilee.py:55(helper) -> 4 0.116 0.120
profilee.py:73(helper1)
+ 2 0.000 0.140
profilee.py:84(helper2_indirect)
+ 6 0.234 0.300
profilee.py:88(helper2)
+profilee.py:73(helper1) -> 4 0.000 0.004
{built-in method builtins.hasattr}
+profilee.py:84(helper2_indirect) -> 2 0.006 0.040
profilee.py:35(factorial)
+ 2 0.078 0.100
profilee.py:88(helper2)
+profilee.py:88(helper2) -> 8 0.064 0.080
profilee.py:98(subhelper)
+profilee.py:98(subhelper) -> 16 0.016 0.016
profilee.py:110(__getattr__)
+{built-in method builtins.hasattr} -> 12 0.012 0.012
profilee.py:110(__getattr__)
1 test OK.
Total duration: 73 ms
Tests result: SUCCESS
----------
components: Tests
messages: 288707
nosy: inada.naoki, xiang.zhang
priority: normal
severity: normal
status: open
title: verbose output of test_cprofile
type: behavior
versions: Python 3.7
_______________________________________
Python tracker <rep...@bugs.python.org>
<http://bugs.python.org/issue29676>
_______________________________________
_______________________________________________
Python-bugs-list mailing list
Unsubscribe:
https://mail.python.org/mailman/options/python-bugs-list/archive%40mail-archive.com
