]> de.git.xonotic.org Git - xonotic/darkplaces.git/blobdiff - prvm_exec.c
Patch by graphitemaster to support column number enhanced lno format.
[xonotic/darkplaces.git] / prvm_exec.c
index 2f8c97aeebc3d12de5e056dc07e4ed1261029017..40b19d44c9b3c91a4628134ec1963c660a094b93 100644 (file)
@@ -21,9 +21,9 @@ Foundation, Inc., 59 Temple Place - Suite 330, Boston, MA  02111-1307, USA.
 #include "quakedef.h"
 #include "progsvm.h"
 
-char *prvm_opnames[] =
+const char *prvm_opnames[] =
 {
-"DONE",
+"^5DONE",
 
 "MUL_F",
 "MUL_V",
@@ -38,31 +38,31 @@ char *prvm_opnames[] =
 "SUB_F",
 "SUB_V",
 
-"EQ_F",
-"EQ_V",
-"EQ_S",
-"EQ_E",
-"EQ_FNC",
+"^2EQ_F",
+"^2EQ_V",
+"^2EQ_S",
+"^2EQ_E",
+"^2EQ_FNC",
 
-"NE_F",
-"NE_V",
-"NE_S",
-"NE_E",
-"NE_FNC",
+"^2NE_F",
+"^2NE_V",
+"^2NE_S",
+"^2NE_E",
+"^2NE_FNC",
 
-"LE",
-"GE",
-"LT",
-"GT",
+"^2LE",
+"^2GE",
+"^2LT",
+"^2GT",
 
-"INDIRECT",
-"INDIRECT",
-"INDIRECT",
-"INDIRECT",
-"INDIRECT",
-"INDIRECT",
+"^6FIELD_F",
+"^6FIELD_V",
+"^6FIELD_S",
+"^6FIELD_ENT",
+"^6FIELD_FLD",
+"^6FIELD_FNC",
 
-"ADDRESS",
+"^1ADDRESS",
 
 "STORE_F",
 "STORE_V",
@@ -71,47 +71,45 @@ char *prvm_opnames[] =
 "STORE_FLD",
 "STORE_FNC",
 
-"STOREP_F",
-"STOREP_V",
-"STOREP_S",
-"STOREP_ENT",
-"STOREP_FLD",
-"STOREP_FNC",
+"^1STOREP_F",
+"^1STOREP_V",
+"^1STOREP_S",
+"^1STOREP_ENT",
+"^1STOREP_FLD",
+"^1STOREP_FNC",
 
-"RETURN",
+"^5RETURN",
 
-"NOT_F",
-"NOT_V",
-"NOT_S",
-"NOT_ENT",
-"NOT_FNC",
+"^2NOT_F",
+"^2NOT_V",
+"^2NOT_S",
+"^2NOT_ENT",
+"^2NOT_FNC",
 
-"IF",
-"IFNOT",
+"^5IF",
+"^5IFNOT",
 
-"CALL0",
-"CALL1",
-"CALL2",
-"CALL3",
-"CALL4",
-"CALL5",
-"CALL6",
-"CALL7",
-"CALL8",
+"^3CALL0",
+"^3CALL1",
+"^3CALL2",
+"^3CALL3",
+"^3CALL4",
+"^3CALL5",
+"^3CALL6",
+"^3CALL7",
+"^3CALL8",
 
-"STATE",
+"^1STATE",
 
-"GOTO",
+"^5GOTO",
 
-"AND",
-"OR",
+"^2AND",
+"^2OR",
 
 "BITAND",
 "BITOR"
 };
 
-char *PRVM_GlobalString (int ofs);
-char *PRVM_GlobalStringNoContents (int ofs);
 
 
 //=============================================================================
@@ -121,46 +119,95 @@ char *PRVM_GlobalStringNoContents (int ofs);
 PRVM_PrintStatement
 =================
 */
-void PRVM_PrintStatement (dstatement_t *s)
+extern cvar_t prvm_statementprofiling;
+extern cvar_t prvm_timeprofiling;
+static void PRVM_PrintStatement(prvm_prog_t *prog, mstatement_t *s)
 {
        size_t i;
+       int opnum = (int)(s - prog->statements);
+       char valuebuf[MAX_INPUTLINE];
 
-       if( prog->statement_linenums ) {
-               int opnum;
-
-               opnum = s - prog->statements;
-               Con_Printf( "%s:%i: ", PRVM_GetString( prog->xfunction->s_file ), prog->statement_linenums[ opnum ] );
+       Con_Printf("s%i: ", opnum);
+       if( prog->statement_linenums )
+       {
+               if ( prog->statement_columnnums )
+                       Con_Printf( "%s:%i:%i: ", PRVM_GetString( prog, prog->xfunction->s_file ), prog->statement_linenums[ opnum ], prog->statement_columnnums[ opnum ] );
+               else
+                       Con_Printf( "%s:%i: ", PRVM_GetString( prog, prog->xfunction->s_file ), prog->statement_linenums[ opnum ] );
        }
 
+       if (prvm_statementprofiling.integer)
+               Con_Printf("%7.0f ", prog->statement_profile[s - prog->statements]);
+
        if ( (unsigned)s->op < sizeof(prvm_opnames)/sizeof(prvm_opnames[0]))
        {
                Con_Printf("%s ",  prvm_opnames[s->op]);
                i = strlen(prvm_opnames[s->op]);
+               // don't count a preceding color tag when padding the name
+               if (prvm_opnames[s->op][0] == STRING_COLOR_TAG)
+                       i -= 2;
                for ( ; i<10 ; i++)
                        Con_Print(" ");
        }
+       if (s->operand[0] >= 0) Con_Printf(  "%s", PRVM_GlobalString(prog, s->operand[0], valuebuf, sizeof(valuebuf)));
+       if (s->operand[1] >= 0) Con_Printf(", %s", PRVM_GlobalString(prog, s->operand[1], valuebuf, sizeof(valuebuf)));
+       if (s->operand[2] >= 0) Con_Printf(", %s", PRVM_GlobalString(prog, s->operand[2], valuebuf, sizeof(valuebuf)));
+       if (s->jumpabsolute >= 0) Con_Printf(", statement %i", s->jumpabsolute);
+       Con_Print("\n");
+}
 
-       if (s->op == OP_IF || s->op == OP_IFNOT)
-               Con_Printf("%sbranch %i",PRVM_GlobalString((unsigned short) s->a),s->b);
-       else if (s->op == OP_GOTO)
+void PRVM_PrintFunctionStatements (prvm_prog_t *prog, const char *name)
+{
+       int i, firststatement, endstatement;
+       mfunction_t *func;
+       func = PRVM_ED_FindFunction (prog, name);
+       if (!func)
        {
-               Con_Printf("branch %i",s->a);
+               Con_Printf("%s progs: no function named %s\n", prog->name, name);
+               return;
        }
-       else if ( (unsigned)(s->op - OP_STORE_F) < 6)
+       firststatement = func->first_statement;
+       if (firststatement < 0)
        {
-               Con_Print(PRVM_GlobalString((unsigned short) s->a));
-               Con_Print(PRVM_GlobalStringNoContents((unsigned short) s->b));
+               Con_Printf("%s progs: function %s is builtin #%i\n", prog->name, name, -firststatement);
+               return;
        }
-       else
+
+       // find the end statement
+       endstatement = prog->numstatements;
+       for (i = 0;i < prog->numfunctions;i++)
+               if (endstatement > prog->functions[i].first_statement && firststatement < prog->functions[i].first_statement)
+                       endstatement = prog->functions[i].first_statement;
+
+       // now print the range of statements
+       Con_Printf("%s progs: disassembly of function %s (statements %i-%i, locals %i-%i):\n", prog->name, name, firststatement, endstatement, func->parm_start, func->parm_start + func->locals - 1);
+       prog->xfunction = func;
+       for (i = firststatement;i < endstatement;i++)
        {
-               if (s->a)
-                       Con_Print(PRVM_GlobalString((unsigned short) s->a));
-               if (s->b)
-                       Con_Print(PRVM_GlobalString((unsigned short) s->b));
-               if (s->c)
-                       Con_Print(PRVM_GlobalStringNoContents((unsigned short) s->c));
+               PRVM_PrintStatement(prog, prog->statements + i);
+               prog->statement_profile[i] = 0;
        }
-       Con_Print("\n");
+}
+
+/*
+============
+PRVM_PrintFunction_f
+
+============
+*/
+void PRVM_PrintFunction_f (void)
+{
+       prvm_prog_t *prog;
+       if (Cmd_Argc() != 3)
+       {
+               Con_Printf("usage: prvm_printfunction <program name> <function name>\n");
+               return;
+       }
+
+       if (!(prog = PRVM_FriendlyProgFromString(Cmd_Argv(1))))
+               return;
+
+       PRVM_PrintFunctionStatements(prog, Cmd_Argv(2));
 }
 
 /*
@@ -168,7 +215,7 @@ void PRVM_PrintStatement (dstatement_t *s)
 PRVM_StackTrace
 ============
 */
-void PRVM_StackTrace (void)
+void PRVM_StackTrace (prvm_prog_t *prog)
 {
        mfunction_t     *f;
        int                     i;
@@ -182,110 +229,320 @@ void PRVM_StackTrace (void)
                if (!f)
                        Con_Print("<NULL FUNCTION>\n");
                else
-                       Con_Printf("%12s : %s : statement %i\n", PRVM_GetString(f->s_file), PRVM_GetString(f->s_name), prog->stack[i].s - f->first_statement);
+               {
+                       if (prog->statement_linenums)
+                       {
+                               if (prog->statement_columnnums)
+                                       Con_Printf("%12s:%i:%i : %s : statement %i\n", PRVM_GetString(prog, f->s_file), prog->statement_linenums[prog->stack[i].s], prog->statement_columnnums[prog->stack[i].s], PRVM_GetString(prog, f->s_name), prog->stack[i].s - f->first_statement);
+                               else
+                                       Con_Printf("%12s:%i : %s : statement %i\n", PRVM_GetString(prog, f->s_file), prog->statement_linenums[prog->stack[i].s], PRVM_GetString(prog, f->s_name), prog->stack[i].s - f->first_statement);
+                       }
+                       else
+                               Con_Printf("%12s : %s : statement %i\n", PRVM_GetString(prog, f->s_file), PRVM_GetString(prog, f->s_name), prog->stack[i].s - f->first_statement);
+               }
        }
 }
 
+void PRVM_ShortStackTrace(prvm_prog_t *prog, char *buf, size_t bufsize)
+{
+       mfunction_t     *f;
+       int                     i;
+       char vabuf[1024];
 
-/*
-============
-PRVM_Profile_f
+       if(prog)
+       {
+               dpsnprintf(buf, bufsize, "(%s) ", prog->name);
+       }
+       else
+       {
+               strlcpy(buf, "<NO PROG>", bufsize);
+               return;
+       }
 
-============
-*/
-void PRVM_Profile_f (void)
+       prog->stack[prog->depth].s = prog->xstatement;
+       prog->stack[prog->depth].f = prog->xfunction;
+       for (i = prog->depth;i > 0;i--)
+       {
+               f = prog->stack[i].f;
+
+               if(strlcat(buf,
+                       f
+                               ? va(vabuf, sizeof(vabuf), "%s:%s(%i) ", PRVM_GetString(prog, f->s_file), PRVM_GetString(prog, f->s_name), prog->stack[i].s - f->first_statement)
+                               : "<NULL> ",
+                       bufsize
+               ) >= bufsize)
+                       break;
+       }
+}
+
+
+static void PRVM_CallProfile (prvm_prog_t *prog)
 {
        mfunction_t *f, *best;
-       int i, num, max/*, howmany*/;
+       int i;
+       double max;
+       double sum;
+       double newprofiletime;
 
-       //howmany = 10;
-       //if (Cmd_Argc() == 2)
-       //      howmany = atoi(Cmd_Argv(1));
-       if(Cmd_Argc() != 2)
+       Con_Printf( "%s Call Profile:\n", prog->name );
+
+       sum = 0;
+       do
        {
-               Con_Print("prvm_profile <program name>\n");
-               return;
-       }
+               max = 0;
+               best = NULL;
+               for (i=0 ; i<prog->numfunctions ; i++)
+               {
+                       f = &prog->functions[i];
+                       if (max < f->totaltime)
+                       {
+                               max = f->totaltime;
+                               best = f;
+                       }
+               }
+               if (best)
+               {
+                       sum += best->totaltime;
+                       Con_Printf("%9.4f %s\n", best->totaltime, PRVM_GetString(prog, best->s_name));
+                       best->totaltime = 0;
+               }
+       } while (best);
 
-       PRVM_Begin;
-       if(!PRVM_SetProgFromString(Cmd_Argv(1)))
-               return;
+       newprofiletime = Sys_DirtyTime();
+       Con_Printf("Total time since last profile reset: %9.4f\n", newprofiletime - prog->profiletime);
+       Con_Printf("       - used by QC code of this VM: %9.4f\n", sum);
 
-       Con_Printf( "%s Profile:\n[Profile] [BuiltinProfile] [CallCount]\n", PRVM_NAME );
+       prog->profiletime = newprofiletime;
+}
+
+void PRVM_Profile (prvm_prog_t *prog, int maxfunctions, double mintime, int sortby)
+{
+       mfunction_t *f, *best;
+       int i, num;
+       double max;
+
+       if(!prvm_timeprofiling.integer)
+               mintime *= 10000000; // count each statement as about 0.1µs
+
+       if(prvm_timeprofiling.integer)
+               Con_Printf( "%s Profile:\n[CallCount]      [Time] [BuiltinTm] [Statement] [BuiltinCt] [TimeTotal] [StmtTotal] [BltnTotal] [self]\n", prog->name );
+               //                        12345678901 12345678901 12345678901 12345678901 12345678901 12345678901 12345678901 123.45%
+       else
+               Con_Printf( "%s Profile:\n[CallCount] [Statement] [BuiltinCt] [StmtTotal] [BltnTotal] [self]\n", prog->name );
+               //                        12345678901 12345678901 12345678901 12345678901 12345678901 123.45%
 
        num = 0;
        do
        {
                max = 0;
                best = NULL;
-               for (i=0 ; i<prog->progs->numfunctions ; i++)
+               for (i=0 ; i<prog->numfunctions ; i++)
                {
                        f = &prog->functions[i];
-                       if (f->profile > max)
+                       if(prvm_timeprofiling.integer)
                        {
-                               max = f->profile;
-                               best = f;
+                               if(sortby)
+                               {
+                                       if(f->first_statement < 0)
+                                       {
+                                               if (max < f->tprofile)
+                                               {
+                                                       max = f->tprofile;
+                                                       best = f;
+                                               }
+                                       }
+                                       else
+                                       {
+                                               if (max < f->tprofile_total)
+                                               {
+                                                       max = f->tprofile_total;
+                                                       best = f;
+                                               }
+                                       }
+                               }
+                               else
+                               {
+                                       if (max < f->tprofile + f->tbprofile)
+                                       {
+                                               max = f->tprofile + f->tbprofile;
+                                               best = f;
+                                       }
+                               }
+                       }
+                       else
+                       {
+                               if(sortby)
+                               {
+                                       if (max < f->profile_total + f->builtinsprofile_total + f->callcount)
+                                       {
+                                               max = f->profile_total + f->builtinsprofile_total + f->callcount;
+                                               best = f;
+                                       }
+                               }
+                               else
+                               {
+                                       if (max < f->profile + f->builtinsprofile + f->callcount)
+                                       {
+                                               max = f->profile + f->builtinsprofile + f->callcount;
+                                               best = f;
+                                       }
+                               }
                        }
                }
                if (best)
                {
-                       //if (num < howmany)
-                               Con_Printf("%7i %7i %7i %s\n", best->profile, best->builtinsprofile, best->callcount, PRVM_GetString(best->s_name));
+                       if (num < maxfunctions && max > mintime)
+                       {
+                               if(prvm_timeprofiling.integer)
+                               {
+                                       if (best->first_statement < 0)
+                                               Con_Printf("%11.0f %11.6f ------------- builtin ------------- %11.6f ----------- builtin ----------- %s\n", best->callcount, best->tprofile, best->tprofile, PRVM_GetString(prog, best->s_name));
+                                       //                 %11.6f 12345678901 12345678901 12345678901 %11.6f 12345678901 12345678901 123.45%
+                                       else
+                                               Con_Printf("%11.0f %11.6f %11.6f %11.0f %11.0f %11.6f %11.0f %11.0f %6.2f%% %s\n", best->callcount, best->tprofile, best->tbprofile, best->profile, best->builtinsprofile, best->tprofile_total, best->profile_total, best->builtinsprofile_total, (best->tprofile_total > 0) ? ((best->tprofile) * 100.0 / (best->tprofile_total)) : -99.99, PRVM_GetString(prog, best->s_name));
+                               }
+                               else
+                               {
+                                       if (best->first_statement < 0)
+                                               Con_Printf("%11.0f ----------------------- builtin ----------------------- %s\n", best->callcount, PRVM_GetString(prog, best->s_name));
+                                       //                 12345678901 12345678901 12345678901 12345678901 123.45%
+                                       else
+                                               Con_Printf("%11.0f %11.0f %11.0f %11.0f %11.0f %6.2f%% %s\n", best->callcount, best->profile, best->builtinsprofile, best->profile_total, best->builtinsprofile_total, (best->profile + best->builtinsprofile) * 100.0 / (best->profile_total + best->builtinsprofile_total), PRVM_GetString(prog, best->s_name));
+                               }
+                       }
                        num++;
                        best->profile = 0;
+                       best->tprofile = 0;
+                       best->tbprofile = 0;
                        best->builtinsprofile = 0;
+                       best->profile_total = 0;
+                       best->tprofile_total = 0;
+                       best->builtinsprofile_total = 0;
                        best->callcount = 0;
                }
        } while (best);
+}
 
-       PRVM_End;
+/*
+============
+PRVM_CallProfile_f
+
+============
+*/
+void PRVM_CallProfile_f (void)
+{
+       prvm_prog_t *prog;
+       if (Cmd_Argc() != 2)
+       {
+               Con_Print("prvm_callprofile <program name>\n");
+               return;
+       }
+
+       if (!(prog = PRVM_FriendlyProgFromString(Cmd_Argv(1))))
+               return;
+
+       PRVM_CallProfile(prog);
 }
 
-void PRVM_CrashAll()
+/*
+============
+PRVM_Profile_f
+
+============
+*/
+void PRVM_Profile_f (void)
 {
-       int i;
-       prvm_prog_t *oldprog = prog;
+       prvm_prog_t *prog;
+       int howmany;
+
+       howmany = 1<<30;
+       if (Cmd_Argc() == 3)
+               howmany = atoi(Cmd_Argv(2));
+       else if (Cmd_Argc() != 2)
+       {
+               Con_Print("prvm_profile <program name>\n");
+               return;
+       }
+
+       if (!(prog = PRVM_FriendlyProgFromString(Cmd_Argv(1))))
+               return;
+
+       PRVM_Profile(prog, howmany, 0, 0);
+}
+
+void PRVM_ChildProfile_f (void)
+{
+       prvm_prog_t *prog;
+       int howmany;
 
-       for(i = 0; i < PRVM_MAXPROGS; i++)
+       howmany = 1<<30;
+       if (Cmd_Argc() == 3)
+               howmany = atoi(Cmd_Argv(2));
+       else if (Cmd_Argc() != 2)
        {
-               if(!PRVM_ProgLoaded(i))
-                       continue;
-               PRVM_SetProg(i);
-               PRVM_Crash();
+               Con_Print("prvm_childprofile <program name>\n");
+               return;
        }
 
-       prog = oldprog;
+       if (!(prog = PRVM_FriendlyProgFromString(Cmd_Argv(1))))
+               return;
+
+       PRVM_Profile(prog, howmany, 0, 1);
 }
 
-void PRVM_PrintState(void)
+void PRVM_PrintState(prvm_prog_t *prog, int stack_index)
 {
        int i;
-       if (prog->xfunction)
+       mfunction_t *func = prog->xfunction;
+       int st = prog->xstatement;
+       if (stack_index > 0 && stack_index <= prog->depth)
+       {
+               func = prog->stack[prog->depth - stack_index].f;
+               st = prog->stack[prog->depth - stack_index].s;
+       }
+       if (prog->statestring)
+       {
+               Con_Printf("Caller-provided information: %s\n", prog->statestring);
+       }
+       if (func)
        {
                for (i = -7; i <= 0;i++)
-                       if (prog->xstatement + i >= prog->xfunction->first_statement)
-                               PRVM_PrintStatement (prog->statements + prog->xstatement + i);
+                       if (st + i >= func->first_statement)
+                               PRVM_PrintStatement(prog, prog->statements + st + i);
        }
-       else
-               Con_Print("null function executing??\n");
-       PRVM_StackTrace ();
+       PRVM_StackTrace(prog);
 }
 
-void PRVM_Crash()
+extern cvar_t prvm_errordump;
+void PRVM_Crash(prvm_prog_t *prog)
 {
+       char vabuf[1024];
        if (prog == NULL)
                return;
+       if (!prog->loaded)
+               return;
+
+       PRVM_serverfunction(SV_Shutdown) = 0; // don't call SV_Shutdown on crash
 
        if( prog->depth > 0 )
        {
-               Con_Printf("QuakeC crash report for %s:\n", PRVM_NAME);
-               PRVM_PrintState();
+               Con_Printf("QuakeC crash report for %s:\n", prog->name);
+               PRVM_PrintState(prog, 0);
+       }
+
+       if(prvm_errordump.integer)
+       {
+               // make a savegame
+               Host_Savegame_to(prog, va(vabuf, sizeof(vabuf), "crash-%s.dmp", prog->name));
        }
 
        // dump the stack so host_error can shutdown functions
        prog->depth = 0;
        prog->localstack_used = 0;
 
+       // delete all tempstrings (FIXME: is this safe in VM->engine->VM recursion?)
+       prog->tempstringsbuf.cursize = 0;
+
        // reset the prog pointer
        prog = NULL;
 }
@@ -305,26 +562,29 @@ PRVM_EnterFunction
 Returns the new program statement counter
 ====================
 */
-int PRVM_EnterFunction (mfunction_t *f)
+static int PRVM_EnterFunction (prvm_prog_t *prog, mfunction_t *f)
 {
        int             i, j, c, o;
 
        if (!f)
-               PRVM_ERROR ("PRVM_EnterFunction: NULL function in %s", PRVM_NAME);
+               prog->error_cmd("PRVM_EnterFunction: NULL function in %s", prog->name);
 
        prog->stack[prog->depth].s = prog->xstatement;
        prog->stack[prog->depth].f = prog->xfunction;
+       prog->stack[prog->depth].profile_acc = -f->profile;
+       prog->stack[prog->depth].tprofile_acc = -f->tprofile + -f->tbprofile;
+       prog->stack[prog->depth].builtinsprofile_acc = -f->builtinsprofile;
        prog->depth++;
        if (prog->depth >=PRVM_MAX_STACK_DEPTH)
-               PRVM_ERROR ("stack overflow");
+               prog->error_cmd("stack overflow");
 
 // save off any locals that the new function steps on
        c = f->locals;
        if (prog->localstack_used + c > PRVM_LOCALSTACK_SIZE)
-               PRVM_ERROR ("PRVM_ExecuteProgram: locals stack overflow in %s", PRVM_NAME);
+               prog->error_cmd("PRVM_ExecuteProgram: locals stack overflow in %s", prog->name);
 
        for (i=0 ; i < c ; i++)
-               prog->localstack[prog->localstack_used+i] = ((int *)prog->globals.generic)[f->parm_start + i];
+               prog->localstack[prog->localstack_used+i] = prog->globals.ip[f->parm_start + i];
        prog->localstack_used += c;
 
 // copy parameters
@@ -333,11 +593,12 @@ int PRVM_EnterFunction (mfunction_t *f)
        {
                for (j=0 ; j<f->parm_size[i] ; j++)
                {
-                       ((int *)prog->globals.generic)[o] = ((int *)prog->globals.generic)[OFS_PARM0+i*3+j];
+                       prog->globals.ip[o] = prog->globals.ip[OFS_PARM0+i*3+j];
                        o++;
                }
        }
 
+       ++f->recursion;
        prog->xfunction = f;
        return f->first_statement - 1;  // offset the s++
 }
@@ -347,31 +608,54 @@ int PRVM_EnterFunction (mfunction_t *f)
 PRVM_LeaveFunction
 ====================
 */
-int PRVM_LeaveFunction (void)
+static int PRVM_LeaveFunction (prvm_prog_t *prog)
 {
        int             i, c;
+       mfunction_t *f;
 
        if (prog->depth <= 0)
-               PRVM_ERROR ("prog stack underflow in %s", PRVM_NAME);
+               prog->error_cmd("prog stack underflow in %s", prog->name);
 
        if (!prog->xfunction)
-               PRVM_ERROR ("PR_LeaveFunction: NULL function in %s", PRVM_NAME);
+               prog->error_cmd("PR_LeaveFunction: NULL function in %s", prog->name);
 // restore locals from the stack
        c = prog->xfunction->locals;
        prog->localstack_used -= c;
        if (prog->localstack_used < 0)
-               PRVM_ERROR ("PRVM_ExecuteProgram: locals stack underflow in %s", PRVM_NAME);
+               prog->error_cmd("PRVM_ExecuteProgram: locals stack underflow in %s", prog->name);
 
        for (i=0 ; i < c ; i++)
-               ((int *)prog->globals.generic)[prog->xfunction->parm_start + i] = prog->localstack[prog->localstack_used+i];
+               prog->globals.ip[prog->xfunction->parm_start + i] = prog->localstack[prog->localstack_used+i];
 
 // up stack
        prog->depth--;
+       f = prog->xfunction;
+       --f->recursion;
        prog->xfunction = prog->stack[prog->depth].f;
+       prog->stack[prog->depth].profile_acc += f->profile;
+       prog->stack[prog->depth].tprofile_acc += f->tprofile + f->tbprofile;
+       prog->stack[prog->depth].builtinsprofile_acc += f->builtinsprofile;
+       if(prog->depth > 0)
+       {
+               prog->stack[prog->depth-1].profile_acc += prog->stack[prog->depth].profile_acc;
+               prog->stack[prog->depth-1].tprofile_acc += prog->stack[prog->depth].tprofile_acc;
+               prog->stack[prog->depth-1].builtinsprofile_acc += prog->stack[prog->depth].builtinsprofile_acc;
+       }
+       if(!f->recursion)
+       {
+               // if f is already on the call stack...
+               // we cannot add this profile data to it now
+               // or we would add it more than once
+               // so, let's only add to the function's profile if it is the outermost call
+               f->profile_total += prog->stack[prog->depth].profile_acc;
+               f->tprofile_total += prog->stack[prog->depth].tprofile_acc;
+               f->builtinsprofile_total += prog->stack[prog->depth].builtinsprofile_acc;
+       }
+       
        return prog->stack[prog->depth].s;
 }
 
-void PRVM_Init_Exec(void)
+void PRVM_Init_Exec(prvm_prog_t *prog)
 {
        // dump the stack
        prog->depth = 0;
@@ -380,73 +664,332 @@ void PRVM_Init_Exec(void)
        // nothing here yet
 }
 
+#define OPA ((prvm_eval_t *)&prog->globals.fp[st->operand[0]])
+#define OPB ((prvm_eval_t *)&prog->globals.fp[st->operand[1]])
+#define OPC ((prvm_eval_t *)&prog->globals.fp[st->operand[2]])
+extern cvar_t prvm_traceqc;
+extern cvar_t prvm_statementprofiling;
+extern qboolean prvm_runawaycheck;
+
+#ifdef PROFILING
+#ifdef CONFIG_MENU
 /*
 ====================
-PRVM_ExecuteProgram
+MVM_ExecuteProgram
 ====================
 */
-// LordHavoc: optimized
-#define OPA ((prvm_eval_t *)&prog->globals.generic[(unsigned short) st->a])
-#define OPB ((prvm_eval_t *)&prog->globals.generic[(unsigned short) st->b])
-#define OPC ((prvm_eval_t *)&prog->globals.generic[(unsigned short) st->c])
-extern cvar_t prvm_boundscheck;
-extern cvar_t prvm_traceqc;
-extern int             PRVM_ED_FindFieldOffset (const char *field);
-extern ddef_t* PRVM_ED_FindGlobal(const char *name);
-void PRVM_ExecuteProgram (func_t fnum, const char *errormessage)
+void MVM_ExecuteProgram (prvm_prog_t *prog, func_t fnum, const char *errormessage)
 {
-       dstatement_t    *st;
+       mstatement_t    *st, *startst;
        mfunction_t     *f, *newf;
        prvm_edict_t    *ed;
        prvm_eval_t     *ptr;
-       int             profile, startprofile, cachedpr_trace, exitdepth;
+       int             jumpcount, cachedpr_trace, exitdepth;
+       int             restorevm_tempstringsbuf_cursize;
+       double  calltime;
+       double tm, starttm;
+       prvm_vec_t tempfloat;
+       // these may become out of date when a builtin is called, and are updated accordingly
+       prvm_vec_t *cached_edictsfields = prog->edictsfields;
+       unsigned int cached_entityfields = prog->entityfields;
+       unsigned int cached_entityfields_3 = prog->entityfields - 3;
+       unsigned int cached_entityfieldsarea = prog->entityfieldsarea;
+       unsigned int cached_entityfieldsarea_entityfields = prog->entityfieldsarea - prog->entityfields;
+       unsigned int cached_entityfieldsarea_3 = prog->entityfieldsarea - 3;
+       unsigned int cached_entityfieldsarea_entityfields_3 = prog->entityfieldsarea - prog->entityfields - 3;
+       unsigned int cached_max_edicts = prog->max_edicts;
+       // these do not change
+       mstatement_t *cached_statements = prog->statements;
+       qboolean cached_allowworldwrites = prog->allowworldwrites;
+       unsigned int cached_flag = prog->flag;
+
+       calltime = Sys_DirtyTime();
+
+       if (!fnum || fnum >= (unsigned int)prog->numfunctions)
+       {
+               if (PRVM_allglobaledict(self))
+                       PRVM_ED_Print(prog, PRVM_PROG_TO_EDICT(PRVM_allglobaledict(self)), NULL);
+               prog->error_cmd("MVM_ExecuteProgram: %s", errormessage);
+       }
+
+       f = &prog->functions[fnum];
+
+       // after executing this function, delete all tempstrings it created
+       restorevm_tempstringsbuf_cursize = prog->tempstringsbuf.cursize;
+
+       prog->trace = prvm_traceqc.integer;
+
+       // we know we're done when pr_depth drops to this
+       exitdepth = prog->depth;
+
+// make a stack frame
+       st = &prog->statements[PRVM_EnterFunction(prog, f)];
+       // save the starting statement pointer for profiling
+       // (when the function exits or jumps, the (st - startst) integer value is
+       // added to the function's profile counter)
+       startst = st;
+       starttm = calltime;
+       // instead of counting instructions, we count jumps
+       jumpcount = 0;
+       // add one to the callcount of this function because otherwise engine-called functions aren't counted
+       prog->xfunction->callcount++;
 
-       if (!fnum || fnum >= (unsigned int)prog->progs->numfunctions)
+chooseexecprogram:
+       cachedpr_trace = prog->trace;
+       if (prvm_statementprofiling.integer || prog->trace || prog->watch_global >= 0 || prog->watch_edict >= 0 || prog->break_statement >= 0)
+       {
+#define PRVMSLOWINTERPRETER 1
+               if (prvm_timeprofiling.integer)
+               {
+#define PRVMTIMEPROFILING 1
+#include "prvm_execprogram.h"
+#undef PRVMTIMEPROFILING
+               }
+               else
+               {
+#include "prvm_execprogram.h"
+               }
+#undef PRVMSLOWINTERPRETER
+       }
+       else
        {
-               if (prog->self && PRVM_G_INT(prog->self->ofs))
-                       PRVM_ED_Print(PRVM_PROG_TO_EDICT(PRVM_G_INT(prog->self->ofs)));
-               PRVM_ERROR ("PRVM_ExecuteProgram: %s", errormessage);
+               if (prvm_timeprofiling.integer)
+               {
+#define PRVMTIMEPROFILING 1
+#include "prvm_execprogram.h"
+#undef PRVMTIMEPROFILING
+               }
+               else
+               {
+#include "prvm_execprogram.h"
+               }
+       }
+
+cleanup:
+       if (developer_insane.integer && prog->tempstringsbuf.cursize > restorevm_tempstringsbuf_cursize)
+               Con_DPrintf("MVM_ExecuteProgram: %s used %i bytes of tempstrings\n", PRVM_GetString(prog, prog->functions[fnum].s_name), prog->tempstringsbuf.cursize - restorevm_tempstringsbuf_cursize);
+       // delete tempstrings created by this function
+       prog->tempstringsbuf.cursize = restorevm_tempstringsbuf_cursize;
+
+       tm = Sys_DirtyTime() - calltime;if (tm < 0 || tm >= 1800) tm = 0;
+       f->totaltime += tm;
+
+       if (prog == SVVM_prog)
+               SV_FlushBroadcastMessages();
+}
+#endif
+
+/*
+====================
+CLVM_ExecuteProgram
+====================
+*/
+void CLVM_ExecuteProgram (prvm_prog_t *prog, func_t fnum, const char *errormessage)
+{
+       mstatement_t    *st, *startst;
+       mfunction_t     *f, *newf;
+       prvm_edict_t    *ed;
+       prvm_eval_t     *ptr;
+       int             jumpcount, cachedpr_trace, exitdepth;
+       int             restorevm_tempstringsbuf_cursize;
+       double  calltime;
+       double tm, starttm;
+       prvm_vec_t tempfloat;
+       // these may become out of date when a builtin is called, and are updated accordingly
+       prvm_vec_t *cached_edictsfields = prog->edictsfields;
+       unsigned int cached_entityfields = prog->entityfields;
+       unsigned int cached_entityfields_3 = prog->entityfields - 3;
+       unsigned int cached_entityfieldsarea = prog->entityfieldsarea;
+       unsigned int cached_entityfieldsarea_entityfields = prog->entityfieldsarea - prog->entityfields;
+       unsigned int cached_entityfieldsarea_3 = prog->entityfieldsarea - 3;
+       unsigned int cached_entityfieldsarea_entityfields_3 = prog->entityfieldsarea - prog->entityfields - 3;
+       unsigned int cached_max_edicts = prog->max_edicts;
+       // these do not change
+       mstatement_t *cached_statements = prog->statements;
+       qboolean cached_allowworldwrites = prog->allowworldwrites;
+       unsigned int cached_flag = prog->flag;
+
+       calltime = Sys_DirtyTime();
+
+       if (!fnum || fnum >= (unsigned int)prog->numfunctions)
+       {
+               if (PRVM_allglobaledict(self))
+                       PRVM_ED_Print(prog, PRVM_PROG_TO_EDICT(PRVM_allglobaledict(self)), NULL);
+               prog->error_cmd("CLVM_ExecuteProgram: %s", errormessage);
        }
 
        f = &prog->functions[fnum];
 
+       // after executing this function, delete all tempstrings it created
+       restorevm_tempstringsbuf_cursize = prog->tempstringsbuf.cursize;
+
        prog->trace = prvm_traceqc.integer;
 
        // we know we're done when pr_depth drops to this
        exitdepth = prog->depth;
 
 // make a stack frame
-       st = &prog->statements[PRVM_EnterFunction (f)];
-       startprofile = profile = 0;
+       st = &prog->statements[PRVM_EnterFunction(prog, f)];
+       // save the starting statement pointer for profiling
+       // (when the function exits or jumps, the (st - startst) integer value is
+       // added to the function's profile counter)
+       startst = st;
+       starttm = calltime;
+       // instead of counting instructions, we count jumps
+       jumpcount = 0;
+       // add one to the callcount of this function because otherwise engine-called functions aren't counted
+       prog->xfunction->callcount++;
 
 chooseexecprogram:
        cachedpr_trace = prog->trace;
-       if (prvm_boundscheck.integer)
+       if (prvm_statementprofiling.integer || prog->trace || prog->watch_global >= 0 || prog->watch_edict >= 0 || prog->break_statement >= 0)
        {
-#define PRVMBOUNDSCHECK 1
-               if (prog->trace)
+#define PRVMSLOWINTERPRETER 1
+               if (prvm_timeprofiling.integer)
                {
-#define PRVMTRACE 1
+#define PRVMTIMEPROFILING 1
 #include "prvm_execprogram.h"
+#undef PRVMTIMEPROFILING
                }
                else
                {
-#undef PRVMTRACE
 #include "prvm_execprogram.h"
                }
+#undef PRVMSLOWINTERPRETER
        }
        else
        {
-#undef PRVMBOUNDSCHECK
-               if (prog->trace)
+               if (prvm_timeprofiling.integer)
                {
-#define PRVMTRACE 1
+#define PRVMTIMEPROFILING 1
 #include "prvm_execprogram.h"
+#undef PRVMTIMEPROFILING
                }
                else
                {
-#undef PRVMTRACE
 #include "prvm_execprogram.h"
                }
        }
+
+cleanup:
+       if (developer_insane.integer && prog->tempstringsbuf.cursize > restorevm_tempstringsbuf_cursize)
+               Con_DPrintf("CLVM_ExecuteProgram: %s used %i bytes of tempstrings\n", PRVM_GetString(prog, prog->functions[fnum].s_name), prog->tempstringsbuf.cursize - restorevm_tempstringsbuf_cursize);
+       // delete tempstrings created by this function
+       prog->tempstringsbuf.cursize = restorevm_tempstringsbuf_cursize;
+
+       tm = Sys_DirtyTime() - calltime;if (tm < 0 || tm >= 1800) tm = 0;
+       f->totaltime += tm;
+
+       if (prog == SVVM_prog)
+               SV_FlushBroadcastMessages();
+}
+#endif
+
+/*
+====================
+SVVM_ExecuteProgram
+====================
+*/
+#ifdef PROFILING
+void SVVM_ExecuteProgram (prvm_prog_t *prog, func_t fnum, const char *errormessage)
+#else
+void PRVM_ExecuteProgram (prvm_prog_t *prog, func_t fnum, const char *errormessage)
+#endif
+{
+       mstatement_t    *st, *startst;
+       mfunction_t     *f, *newf;
+       prvm_edict_t    *ed;
+       prvm_eval_t     *ptr;
+       int             jumpcount, cachedpr_trace, exitdepth;
+       int             restorevm_tempstringsbuf_cursize;
+       double  calltime;
+       double tm, starttm;
+       prvm_vec_t tempfloat;
+       // these may become out of date when a builtin is called, and are updated accordingly
+       prvm_vec_t *cached_edictsfields = prog->edictsfields;
+       unsigned int cached_entityfields = prog->entityfields;
+       unsigned int cached_entityfields_3 = prog->entityfields - 3;
+       unsigned int cached_entityfieldsarea = prog->entityfieldsarea;
+       unsigned int cached_entityfieldsarea_entityfields = prog->entityfieldsarea - prog->entityfields;
+       unsigned int cached_entityfieldsarea_3 = prog->entityfieldsarea - 3;
+       unsigned int cached_entityfieldsarea_entityfields_3 = prog->entityfieldsarea - prog->entityfields - 3;
+       unsigned int cached_max_edicts = prog->max_edicts;
+       // these do not change
+       mstatement_t *cached_statements = prog->statements;
+       qboolean cached_allowworldwrites = prog->allowworldwrites;
+       unsigned int cached_flag = prog->flag;
+
+       calltime = Sys_DirtyTime();
+
+       if (!fnum || fnum >= (unsigned int)prog->numfunctions)
+       {
+               if (PRVM_allglobaledict(self))
+                       PRVM_ED_Print(prog, PRVM_PROG_TO_EDICT(PRVM_allglobaledict(self)), NULL);
+               prog->error_cmd("SVVM_ExecuteProgram: %s", errormessage);
+       }
+
+       f = &prog->functions[fnum];
+
+       // after executing this function, delete all tempstrings it created
+       restorevm_tempstringsbuf_cursize = prog->tempstringsbuf.cursize;
+
+       prog->trace = prvm_traceqc.integer;
+
+       // we know we're done when pr_depth drops to this
+       exitdepth = prog->depth;
+
+// make a stack frame
+       st = &prog->statements[PRVM_EnterFunction(prog, f)];
+       // save the starting statement pointer for profiling
+       // (when the function exits or jumps, the (st - startst) integer value is
+       // added to the function's profile counter)
+       startst = st;
+       starttm = calltime;
+       // instead of counting instructions, we count jumps
+       jumpcount = 0;
+       // add one to the callcount of this function because otherwise engine-called functions aren't counted
+       prog->xfunction->callcount++;
+
+chooseexecprogram:
+       cachedpr_trace = prog->trace;
+       if (prvm_statementprofiling.integer || prog->trace || prog->watch_global >= 0 || prog->watch_edict >= 0 || prog->break_statement >= 0)
+       {
+#define PRVMSLOWINTERPRETER 1
+               if (prvm_timeprofiling.integer)
+               {
+#define PRVMTIMEPROFILING 1
+#include "prvm_execprogram.h"
+#undef PRVMTIMEPROFILING
+               }
+               else
+               {
+#include "prvm_execprogram.h"
+               }
+#undef PRVMSLOWINTERPRETER
+       }
+       else
+       {
+               if (prvm_timeprofiling.integer)
+               {
+#define PRVMTIMEPROFILING 1
+#include "prvm_execprogram.h"
+#undef PRVMTIMEPROFILING
+               }
+               else
+               {
+#include "prvm_execprogram.h"
+               }
+       }
+
+cleanup:
+       if (developer_insane.integer && prog->tempstringsbuf.cursize > restorevm_tempstringsbuf_cursize)
+               Con_DPrintf("SVVM_ExecuteProgram: %s used %i bytes of tempstrings\n", PRVM_GetString(prog, prog->functions[fnum].s_name), prog->tempstringsbuf.cursize - restorevm_tempstringsbuf_cursize);
+       // delete tempstrings created by this function
+       prog->tempstringsbuf.cursize = restorevm_tempstringsbuf_cursize;
+
+       tm = Sys_DirtyTime() - calltime;if (tm < 0 || tm >= 1800) tm = 0;
+       f->totaltime += tm;
+
+       if (prog == SVVM_prog)
+               SV_FlushBroadcastMessages();
 }