Melhoria - Calcular um tempo de execução de uma rotina

Situação
Ocorrência
Correção/Melhoria/Implementação
Informação adicional

Situação
Ocorrência
Correção/Melhoria/Implementação
Informação adicional

Situação
Ocorrência
Correção/Melhoria/Implementação
Informação adicional

Situação

Calculo do tempo de execução de uma rotina.

Ocorrencia

Necessidade de calcular o tempo de execução de uma determinada rotina automaticamente.

Melhoria

Realizado uma melhoria no Server e no remote para que no log de console do server e do remote, sejam apresentadas as mensagens de tempo de execução da rotina.

Log no servidor.

Cronometra, primeiro, o momento em que leu o parametro ‘logmessages=1’, até o ponto em que passa o controle para o client a primeira vez, e assim que recebe o controle de volta até o momento que devolve o controle para o client novamente, logando sempre esses intervalos, que podemos considerar o processamento do servidor para aquele ‘lote’ de mensagens. Exemplo:

[090721 14:22:34.953] T 4312 O 3000 [S*>] MS_RETURN | BIT_END | BIT_COMMIT

[ 00:00:00.0] T 4312 O 3000 [__R] CRONOMETRO

[090721 14:22:35.281] T 4312 O 3000 [__R] MS_UPDATESERVERCOORDS

[090721 14:22:35.281] T 4312 O 3001 [__R] MS_UPDATESERVERCOORDS

[090721 14:22:35.281] T 4312 O 3002 [__R] MS_UPDATESERVERCOORDS

[090721 14:22:35.281] T 4312 O 3003 [__R] MS_UPDATESERVERCOORDS

[090721 14:22:35.281] T 4312 O 3004 [__R] MS_UPDATESERVERCOORDS

[090721 14:22:35.281] T 4312 O 3005 [__R] MS_UPDATESERVERCOORDS

[090721 14:22:35.281] T 4312 O 3007 [__R] MS_UPDATESERVERCOORDS

[090721 14:22:35.281] T 4312 O 3014 [__R] MS_UPDATESERVERCOORDS

[090721 14:22:35.281] T 4312 O 3015 [__R] MS_UPDATESERVERCOORDS

[090721 14:22:35.281] T 4312 O 3016 [__R] MS_UPDATESERVERCOORDS

[090721 14:22:35.281] T 4312 O 3012 [__R] MS_CLICK | BIT_COMMIT

[090721 14:22:35.562] T 4312 O 3002 [S__] MS_SETENABLED

[090721 14:22:35.562] T 4312 O 3007 [S__] MS_HIDE

[090721 14:22:35.562] T 4312 O 3006 [S__] MS_SHOW

[090721 14:22:35.562] T 4312 O 3017 [S__] MS_UPDATECLIENTVALUE

[090721 14:22:35.562] T 4312 O 3002 [S__] MS_SETFOLDEROPTION

[090721 14:22:35.578] T 4312 O 1 [SW>] MS_CURSORWAIT | BIT_COMMIT

[ 00:00:00.281] T 4312 O 1 [__R] CRONOMETRO

...

Log no client.

Cronometra todas as mensagens enviadas do server ao client, mostrando o tempo que levou para processar cada uma mensagem:

[21/07/2009 14:22:31] (MS_SETCENTURYON)<-

[21/07/2009 14:22:31] 00:00:00.0

[21/07/2009 14:22:31] (MS_GETSETACENTO)<-

[21/07/2009 14:22:31] 00:00:00.0

[21/07/2009 14:22:31] (MS_CREATEFORM)<-

[21/07/2009 14:22:31] 00:00:00.230

[21/07/2009 14:22:31] (MS_SETSTYLE)<-

[21/07/2009 14:22:31] 00:00:00.0

[21/07/2009 14:22:31] (MS_SETBOUNDS)<-

[21/07/2009 14:22:31] 00:00:00.0

[21/07/2009 14:22:31] ->(MS_UPDATESERVERCOORDS)

[21/07/2009 14:22:31] ->(MS_FORMSTART | BIT_COMMIT)

[21/07/2009 14:22:31] (MS_SETFOCUS)<-

[21/07/2009 14:22:31] 00:00:00.0

Informação adicional

Para essas informações serem logada, é necessáro habilitar logmessages=1 no totvsappserver.ini do Totvsappserver, na seção GENERAL.

Árvore de páginas

Melhoria - Calcular um tempo de execução de uma rotina

Melhoria - Calcular um tempo de execução de uma rotina