oh thats pretty weird, what is the actual codepath being executed
during this test ? they seem completely different.
On Feb 24, 2006, at 8:53 AM, dmiller wrote:
On Feb 24, 2006, at 1:17 AM, Michael Bayer wrote:
yah well good luck with the profiling. this particular SA bug has
probably been there the entire time in the source tree.
Before (showing all calls in schema.py):
13023 function calls (11087 primitive calls) in 3.680 CPU
seconds
Ordered by: standard name
ncalls tottime percall cumtime percall filename:lineno
(function)
...
1 0.000 0.000 0.000 0.000 schema.py:1(?)
1 0.000 0.000 0.000 0.000 schema.py:104(__init__)
1 0.010 0.010 0.260 0.260 schema.py:16(?)
1 0.000 0.000 0.000 0.000 schema.py:214(Column)
2 0.000 0.000 0.000 0.000 schema.py:216(__init__)
1 0.000 0.000 0.000 0.000 schema.py:26(SchemaItem)
1 0.000 0.000 2.590 2.590 schema.py:28
(_init_items)
1 0.000 0.000 2.590 2.590 schema.py:282
(_set_parent)
1 0.000 0.000 0.000 0.000 schema.py:353
(ForeignKey)
1 0.000 0.000 0.000 0.000 schema.py:422
(DefaultGenerator)
1 0.000 0.000 0.000 0.000 schema.py:431
(PassiveDefault)
1 0.000 0.000 0.000 0.000 schema.py:440
(ColumnDefault)
1 0.000 0.000 0.000 0.000 schema.py:451(Sequence)
1 0.000 0.000 0.000 0.000 schema.py:468(Index)
986/1 2.590 0.003 2.590 2.590 schema.py:49
(__getattr__)
1 0.000 0.000 0.000 0.000 schema.py:515
(SchemaEngine)
1 0.000 0.000 0.000 0.000 schema.py:534
(SchemaVisitor)
1 0.000 0.000 0.000 0.000 schema.py:56
(_get_table_key)
1 0.000 0.000 0.000 0.000 schema.py:62
(__classinit__)
1 0.000 0.000 0.000 0.000 schema.py:64
(TableSingleton)
1 0.000 0.000 2.590 2.590 schema.py:66(__call__)
1 0.000 0.000 0.000 0.000 schema.py:7(Schema)
1 0.000 0.000 0.000 0.000 schema.py:98(Table)
...
After (showing all calls in schema.py):
29992 function calls (28782 primitive calls) in 2.190 CPU
seconds
Ordered by: standard name
ncalls tottime percall cumtime percall filename:lineno
(function)
...
1 0.000 0.000 0.010 0.010 schema.py:1(?)
18 0.010 0.001 0.010 0.001 schema.py:104(__init__)
1 0.010 0.010 0.220 0.220 schema.py:16(?)
18 0.000 0.000 0.000 0.000 schema.py:163(hash_key)
3 0.000 0.000 0.000 0.000 schema.py:187
(_from_python)
49 0.010 0.000 0.040 0.001 schema.py:188
(accept_visitor)
1 0.000 0.000 0.000 0.000 schema.py:214(Column)
134 0.000 0.000 0.000 0.000 schema.py:216(__init__)
1 0.000 0.000 0.000 0.000 schema.py:26(SchemaItem)
370 0.010 0.000 0.010 0.000 schema.py:261(<lambda>)
44 0.000 0.000 0.000 0.000 schema.py:262(<lambda>)
53 0.000 0.000 0.000 0.000 schema.py:263(<lambda>)
30 0.000 0.000 0.000 0.000 schema.py:263(add_field)
116/22 0.010 0.000 0.040 0.002 schema.py:28
(_init_items)
94 0.010 0.000 0.030 0.000 schema.py:282
(_set_parent)
40 0.000 0.000 0.020 0.000 schema.py:309
(_make_proxy)
585 0.000 0.000 0.010 0.000 schema.py:328
(accept_visitor)
29 0.000 0.000 0.000 0.000 schema.py:339(__eq__)
1 0.010 0.010 0.010 0.010 schema.py:353
(ForeignKey)
17 0.000 0.000 0.000 0.000 schema.py:356(__init__)
4 0.000 0.000 0.000 0.000 schema.py:367(copy)
4 0.000 0.000 0.000 0.000 schema.py:371
(_get_colspec)
38 0.000 0.000 0.030 0.001 schema.py:379
(references)
55 0.010 0.000 0.020 0.000 schema.py:383
(_init_column)
55 0.000 0.000 0.020 0.000 schema.py:407(<lambda>)
106 0.000 0.000 0.000 0.000 schema.py:409
(accept_visitor)
17 0.000 0.000 0.000 0.000 schema.py:413
(_set_parent)
1 0.000 0.000 0.000 0.000 schema.py:422
(DefaultGenerator)
11 0.000 0.000 0.000 0.000 schema.py:425
(_set_parent)
1 0.000 0.000 0.000 0.000 schema.py:431
(PassiveDefault)
1 0.000 0.000 0.000 0.000 schema.py:440
(ColumnDefault)
1 0.000 0.000 0.000 0.000 schema.py:451(Sequence)
11 0.000 0.000 0.000 0.000 schema.py:453(__init__)
127 0.000 0.000 0.000 0.000 schema.py:464
(accept_visitor)
1 0.000 0.000 0.000 0.000 schema.py:468(Index)
809 0.030 0.000 0.030 0.000 schema.py:49
(__getattr__)
1 0.000 0.000 0.000 0.000 schema.py:515
(SchemaEngine)
1 0.000 0.000 0.000 0.000 schema.py:534
(SchemaVisitor)
33 0.000 0.000 0.000 0.000 schema.py:539
(visit_table)
525 0.000 0.000 0.000 0.000 schema.py:542
(visit_column)
106 0.000 0.000 0.000 0.000 schema.py:545
(visit_foreign_key)
127 0.000 0.000 0.000 0.000 schema.py:557
(visit_sequence)
31 0.010 0.000 0.010 0.000 schema.py:56
(_get_table_key)
4 0.010 0.003 0.020 0.005 schema.py:62
(__classinit__)
1 0.000 0.000 0.000 0.000 schema.py:64
(TableSingleton)
31 0.000 0.000 0.060 0.002 schema.py:66(__call__)
1 0.000 0.000 0.000 0.000 schema.py:7(Schema)
3 0.000 0.000 0.000 0.000 schema.py:86
(__initargs__)
1 0.000 0.000 0.000 0.000 schema.py:98(Table)
...
Strange... that definitely made a difference though:
Before
986/1 2.590 0.003 2.590 2.590 schema.py:49
(__getattr__)
After
809 0.030 0.000 0.030 0.000 schema.py:49
(__getattr__)
The updated version has almost twice as many function calls but is
more than a second faster over all... Which makes me wonder, are
all those extra function calls needed? It seemed to be working fine
without them.
Also, now it seems to load faster (when I'm not profiling of course).
~ Daniel
-------------------------------------------------------
This SF.Net email is sponsored by xPML, a groundbreaking scripting
language
that extends applications into web and mobile media. Attend the
live webcast
and join the prime developer group breaking into this new coding
territory!
http://sel.as-us.falkag.net/sel?
cmd=lnk&kid=110944&bid=241720&dat=121642
_______________________________________________
Sqlalchemy-users mailing list
Sqlalchemy-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/sqlalchemy-users
-------------------------------------------------------
This SF.Net email is sponsored by xPML, a groundbreaking scripting language
that extends applications into web and mobile media. Attend the live webcast
and join the prime developer group breaking into this new coding territory!
http://sel.as-us.falkag.net/sel?cmd=lnk&kid=110944&bid=241720&dat=121642
_______________________________________________
Sqlalchemy-users mailing list
Sqlalchemy-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/sqlalchemy-users
