I agree, was looking at it as well. Idea was take the time of all optimizers together.

sql>call querylog_enable(); sql>select * from querylog_catalog(); +----+-------+---------+-------+------+-----+----------+ | id | owner | defined | query | pipe | mal | optimize | +====+=======+=========+=======+======+=====+==========+ +----+-------+---------+-------+------+-----+----------+ 0 tuples (1.752ms) sql>call querylog_enable(); sql>select count(*) from tables; +------+ | L1 | +======+ | 33 | +------+ 1 tuple (3.112ms) sql>select * from querylog_catalog(); +-----------+---------+----------------------------+------------------------------+--------------+------+----------+ | id | owner | defined | query | pipe | mal | optimize | +===========+=========+============================+==============================+==============+======+==========+ | 1359324@0 | monetdb | 2013-03-20 13:22:14.000000 | select count(*) from tables; | default_pipe | 54 | 69124831 | +-----------+---------+----------------------------+------------------------------+--------------+------+----------+ 1 tuple (1.740ms) sql>select * from querylog_calls(); +-----------+----------------------------+----------------------------+--------------+--------+------+------+------+------+-------+ | id | start | stop | arguments | tuples | run | ship | cpu | io | space | +===========+============================+============================+==============+========+======+======+======+======+=======+ | 1359324@0 | 2013-03-20 13:22:14.000000 | 2013-03-20 13:22:14.000000 | user.s3_1(); | 1 | 742 | 14 | 0 | 0 | 0 | +-----------+----------------------------+----------------------------+--------------+--------+------+------+------+------+-------+

On 3/20/13 2:21 PM, Niels Nes wrote:

Changeset: 6bd79fe72615 for MonetDB URL: http://dev.monetdb.org/hg/MonetDB?cmd=changeset;node=6bd79fe72615 Modified Files: monetdb5/mal/mal_runtime.c sql/backends/monet5/sql_gencode.c Branch: default Log Message:

Toptimize doesn't measure anything usefull, ie just store 0 for the time being (solves all problems with changing output). We need a different solution to store the time spend in the optimizer stack.

diffs (34 lines):

diff --git a/monetdb5/mal/mal_runtime.c b/monetdb5/mal/mal_runtime.c --- a/monetdb5/mal/mal_runtime.c +++ b/monetdb5/mal/mal_runtime.c @@ -172,8 +172,8 @@ runtimeProfileExit(Client cntxt, MalBlkP if (malProfileMode == 0) return; /* mostly true */

• if (stk != NULL && prof->stkpc >= 0 && mb->profiler != NULL && mb->profiler[stkpc].trace && mb->profiler[stkpc].clk)
• {

• if (stk != NULL && prof->stkpc >= 0 && mb->profiler != NULL &&

• mb->profiler[stkpc].trace && mb->profiler[stkpc].clk) {
  

  MT_lock_set(&mal_contextLock, "sysmon"); gettimeofday(&mb->profiler[stkpc].clock, NULL); mb->profiler[stkpc].counter++;

diff --git a/sql/backends/monet5/sql_gencode.c b/sql/backends/monet5/sql_gencode.c --- a/sql/backends/monet5/sql_gencode.c +++ b/sql/backends/monet5/sql_gencode.c @@ -2408,7 +2408,7 @@ backend_dumpproc(backend *be, Client c, { char *t; InstrPtr q;

• lng Toptimize = GDKusec();
  

• lng Toptimize = 0;
  
  

  if ( be->q && be->q->codestring) { t = GDKstrdup( be->q->codestring);

@@ -2421,7 +2421,6 @@ backend_dumpproc(backend *be, Client c, q->token = REMsymbol; // will be patched q = pushStr(mb, q, t); q = pushStr(mb, q, pipe= initSQLoptimizer());

• Toptimize = GDKusec() - Toptimize;
  

  (void) pushLng(mb, q, Toptimize); m->Tparse = 0; GDKfree(pipe);

---

checkin-list mailing list checkin-list@monetdb.org http://mail.monetdb.org/mailman/listinfo/checkin-list