Saltar para o conteúdo principal
Versão: 19

Descrição de arquivos de histórico

Aplicações 4D podem gerar vários arquivos de histórico ou log que são úteis para depuração e otimizar sua execução. Logs are usually started or stopped using selectors of the SET DATABASE PARAMETER or WEB SET OPTION commands and are stored in the Logs folder of the project.

Informação gravada precisa ser analisada para detectar e corrigir os problemas. Esta seção oferece uma descrição detalhada dos arquivos de log abaixo:

Note: When a log file can be generated either on 4D Server or on the remote client, the word "Server" is added to the server-side log file name, for example "4DRequestsLogServer.txt"

Arquivos de Histórico compartilham alguns campos para que possa estabelecer uma cronologia e fazer conexões entre entradas quando depurar:

  • sequence_number: esse número é unico entre todos os arquivos de histórico e é aumentado a cada nova entrada qualquer que seja o arquivo de log, para que possa saber a sequência exata das operações.
  • connection_uuid: para qualquer processo 4D criado em um cliente 4D que conecte a u m servidor, essa conexão UUID é registrada tanto no servior quanto no lado do cliente. Permite que facilmente identifique o cliente remoto que lança cada processo.

4DRequestsLog.txt

Esses arquivos de histórico gravam petições normais realizadas pela máquina 4D Server ou a máquina remota 4D que executaram o comando (excluindo petições Web).

Como iniciar esse log:

  • no servidor:
SET DATABASE PARAMETER(4D Server log recording;1)
//lado servidor
  • em um cliente:
SET DATABASE PARAMETER(Client Log Recording;1)
//lado remoto

Esta declaração também começa o arquivo de histórico 4DRequestsLog.txt.

Cabeçalhos

Este arquivo começa com os cabeçalhos abaixo:

  • Identificador de sessão de histórico
  • Hostname do servidor que hospeda a aplicação
  • Nome login Usuário: login do SO do usuário que roda a aplicação 4D no servidor.

Conteúdos

Para cada petição, os campos abaixo estão logados:

Campo nomeDescrição
sequence_numberNúmero de operação único e sequencial da sessão de histórico
timeData e hora usando formato ISO 8601: 'YYYY-MM-DDTHH:MM:SS.mmm'
systemidID de sistema
componenteAssinatura de componente (por exemplo '4SQLS' ou 'dbmg')
process_info_index Corresponds to the "index" field in 4DRequestsLog_ProcessInfo.txt log, and permits linking a request to a process.
requestID de petição em C/S ou string de mensagem para petições SQL ou mensagens LOG EVENT
bytes_inNúmero de bytes recebidos
bytes_outNúmero de bytes enviados
server_duration | exec_durationDepende de onde o histórico for gerado:
  • server_duration se for gerado no cliente - tempo que levou em microssegundos para o servidor processar a petição e retornar a resposta. B a F na imagem abaixo OU
  • exec_duration se gerado no servidor - tempo que levou em microssegundos para o servidor processar a petição. B a E na imagem abaixo.
  • write_durationTempo que levou em microssegundos para enviar:
  • a petição (quando rodar no cliente). A a B na imagem abaixo.
  • Resposta (quando rodar no servidor). E a F na imagem abaixo.
  • task_kindPreemptivo ou cooperativo (respectivamente 'p' ou 'c')
    rttTempo estimado em microssegundos para o cliente enviar a petição e o servidor para reconhecê-la. A a D e E a H na imagem abaixo.
  • Só medido quando usar a capa de rede ServerNet, retorna 0 se usado com capa de rede legado.
  • Para versões de Windows anteriores a Windows 10 ou Windows Server 2016, a chamada retorna 0.
  • Fluxo de petição:

    4DRequestsLog_ProcessInfo.txt

    Este arquivo de histórico registra a informação em cada processo criado na máquina 4D Server ou na máquina remota 4D que executa o comando (excluindo petições Web).

    Como iniciar esse log:

    • no servidor:
    SET DATABASE PARAMETER(4D Server log recording;1) //lado servidor
    • em um cliente:
    SET DATABASE PARAMETER(Client Log Recording;1) //lado remoto

    Esta declaração também começa um arquivo de histórico 4DRequestsLog_ProcessInfo.txt.

    Cabeçalhos

    Este arquivo começa com os cabeçalhos abaixo:

    • Identificador de sessão de histórico
    • Hostname do servidor que hospeda a aplicação
    • Nome login Usuário: login do SO do usuário que roda a aplicação 4D no servidor.

    Conteúdos

    Para cada processo, os campos abaixo são registrados:

    Campo nomeDescrição
    sequence_numberNúmero de operação único e sequencial da sessão de histórico
    timeHora e data usando o formato ISO 8601 format: "YYYY-MM-DDTHH:MM:SS.mmm"
    process_info_indexNúmero de processo único e sequencial
    CDB4DBaseContextDB4D contexto de componente database UUID
    systemidID de sistema
    server_process_idID de processo no servidor
    remote_process_idId de processo no cliente
    process_nameNome de processo
    cIDIdentificador de conexão 4D
    uIDIdentificador de Cliente 4D
    IP ClientIPv4/IPv6アドレス
    host_nameHostname do cliente
    user_nameNome de login de usuário no cliente
    connection_uuidIdentificador UUID da conexão de processo
    server_process_unique_idID de Processo único em Servidor

    HTTPDebugLog.txt

    Este arquivo registra cada petição HTTP e cada resposta em modo raw (não processado). Petições inteiras, incluindo cabeçalhos, são registradas; opcionalmente, partes do corpo podem ser registradas também.

    Como iniciar esse log:

    WEB SET OPTION(Web debug log;wdl enable without body)  
    //outros valores estão disponíveis

    Os campos abaixo são registrados tanto para Request quanto para Response:

    Campo nomeDescrição
    SocketIDID da socked usada para comunicação
    PeerIPEndereço IPv4 do host (cliente)
    PeerPortPorta usada por host (cliente)
    TimeStampTimestamp em milisegundos (desde início sistema)
    ConnectionIDConnection UUID (UUID de VTCPSocket usada para comunicação)
    SequenceNumberNúmero de operação único e sequencial da sessão de histórico

    4DDebugLog.txt (standard)

    Esses registros de histórico gravam cada evento que ocorreu ao nível de programação de 4D. Modo padrão oferece uma vista básica de eventos.

    Como iniciar esse log:

    SET DATABASE PARAMETER(Debug Log Recording;2)  
    //standard, all processes SET DATABASE PARAMETER(Current process debug log recording;2)
    //standard, current process only

    Os campos abaixo estão registrados para cada evento:

    Coluna #Descrição
    1Número de operação único e sequencial da sessão de histórico
    2Data e hora em formato ISO 8601 (YYYY-MM-DDThh:mm:ss.mmm)
    3ID de processo (p=xx) e ID de processo único (puid=xx)
    4Nível de stack
    5Pode ser Command Name/ Method Name/Message/ Task Start Stop info/Plugin Name, event ou Callback/Connection UUID
    6Tempo que leva para a operação de histórico em milissegundos

    4DDebugLog.txt (tabular)

    Esse arquivo de histórico registra cada evento que ocorrer ao nível de programação 4D em um formato tabulado e compacto que inclui informação adicional (comparado ao formato padrão).

    Como iniciar esse log:

    SET DATABASE PARAMETER(Debug Log Recording;2+4)  
    //extended tabbed format, all processes SET DATABASE PARAMETER(Current process debug log recording;2+4)
    //extended, current process only

    Os campos abaixo estão registrados para cada evento:

    Coluna #Campo nomeDescrição
    1sequence_numberNúmero de operação único e sequencial da sessão de histórico
    2timeData e hora em formato ISO 8601 (YYYY-MM-DDThh:mm:ss.mmm)
    3ProcessIDProcess ID
    4unique_processIDID de processo
    5stack_levelNível de stack
    6operation_typeTipo operação histórico. Esse valor pode ser um valor absoluto:
    1. Comando
    2. Método (método de projeto, método de banco de dados, etc)
    3. Mensagem (enviada só pelo comando LOG EVENT)
    4. PluginMessage
    5. PluginEvent
    6. PluginCommand
    7. PluginCallback
    8. Tarefa
    9. Método membro (método anexado à coleção ou a um objeto)
    Quando fechar um nível de stack, as colunas operation_type, operation e operation_parameters tem o mesmo valor que o nível de stack registrado na coluna stack_opening_sequence_number column. Por exemplo:
    1. 121 15:16:50:777 5 8 1 2 CallMethod Parameters 0
    2. 122 15:16:50:777 5 8 2 1 283 0
    3. 123 15:16:50:777 5 8 2 1 283 0 122 3
    4. 124 15:16:50:777 5 8 1 2 CallMethod Parameters 0 121 61
    A primeira e segunda linha abrem o nível de stack, a terceira e quarta linha fecham o nível de stack. Valores nas colunas 6, 7 e 8 são repetidos na linha do nível de stack ao fechar. A coluna 10 contém os números de sequência de abertura do nível de stack, ou seja, 122 para a terceira linha e 121 para a quarta.
    7operationPode representar (dependendo do tipo da operação):
  • uma ID de comando de linguagem (quando tipo =1)
  • um nome de método (quando tipo =2)
  • uma combinação de pluginIndex;pluginCommand (quando tipo=4, 5, 6 ou 7). Pode conter algo como '3;2'
  • uma UUID task connection (quando tipo = 8)
  • 8operation_parametersParâmetros passados a comandos, métodos ou plugins
    9form_eventEvento formulário se houver: vazio em outros casos (suponha que a coluna é usada quando o código for executado em um método formulário ou método objeto)
    10stack_opening_sequence_numberSó para níveis stack fechamento: número sequencia do nível correspondente de abertura de stack
    11stack_level_execution_timeSó para níveis de stack de fechamento: tempo decorrido em microssegundos da ação de histórico atual; só para os níveis de stack de fechamento (ver décima coluna em linhas 123 e 124 no histórico acima)

    4DDiagnosticLog.txt

    Esse arquivo registra vários eventos relacionados à operação de aplicação e sua leitura humana. Pode incluir informação personalizada nesse arquivo usando o comando LOG EVENT

    Como iniciar esse log:

     SET DATABASE PARAMETER(Diagnostic log recording;1) //começa o registro

    Os campos abaixo estão registrados para cada evento:

    Nome de campoDescrição
    sequenceNumberNúmero de operação único e sequencial da sessão de histórico
    timestampData e hora em formato ISO 8601 (YYYY-MM-DDThh:mm:ss.mmm)
    loggerIDOpcional
    componentSignatureOpcional - assinatura interna de componente
    messageLevelInformação, Aviso, Erro
    messageDescrição da entrada de histórico

    Dependendo do evento, vários outros campos podem ser registrados, como task, socket, etc.

    4DSMTPLog.txt, 4DPOP3Log.txt, e 4DIMAPLog.txt

    Esses históricos registram cada troca entre a aplicação 4D e o servidor de mail (SMTP, POP3, IMAP) que foram iniciados pelos comandos abaixo:

    Os arquivos de histórico podem ser produzidos em duas versões:

    • uma versão comum:
      • chamada 4DSMTPLog.txt, 4DPOP3Log.txt, ou 4DIMAPLog.txt
      • sem anexos
      • usa um arquivo circular automático que é reciclado a cada 10MB
      • para depurações comuns

    Para começar esse histórico:

    SET DATABASE PARAMETER(SMTP Log;1) //start SMTP log 
    SET DATABASE PARAMETER(POP3 Log;1) //start POP3 log
    SET DATABASE PARAMETER(IMAP Log;1) //start IMAP log

    4D Server: Click on the Start Request and Debug Logs button in the Maintenance Page of the 4D Server administration window.

    A rota do histórico é retornada pelo comando Get 4D file.

    • uma versão estendida:
      • attachment(s) included no automatic recycling
      • nome personalizado
      • guardado para propósitos específicos

    Para começar esse histórico:

    $server:=New object
    ...
    //SMTP
    $server.logFile:="MySMTPAuthLog.txt"
    $transporter:=SMTP New transporter($server)

    // POP3
    $server.logFile:="MyPOP3AuthLog.txt"
    $transporter:=POP3 New transporter($server)

    //IMAP
    $server.logFile:="MyIMAPAuthLog.txt"
    $transporter:=IMAP New transporter($server)

    Conteúdos

    Para cada petição, os campos abaixo estão logados:

    Coluna #Descrição
    1Número de operação único e sequencial da sessão de histórico
    2Data e hora em formato RFC3339 (yyyy-mm-ddThh:mm:ss.ms)
    3ID de 4D Process
    4ID de processo
    5
    • Informação de início de SMTP,POP3, ou sessão IMAP, incluindo nome de servidor host, número de porta TCP usada para conectar com servidor SMTP,POP3, ou IMAP e status de TLS, ou
    • troca de dados entre servidor e cliente, começando com "S <" (dados recebidos de servidor SMTP,POP3, ou IMAP) ou "C >" (dados enviados pelo cliente SMTP,POP3, ou IMAP client): lista de modo autenticação enviada pelo servidor e seleciona modo autenticação, qualquer erro relatado por servidor SMTP,POP3, ou IMAP, informação de cabeçalho do mail enviado (apenas na versão normal) e se o mail foi salvo no servidor, ou
    • Informação de fechamento de sessão de SMPT, POP3 ou IMAP.

    Petições cliente ORDA

    Esse registro grava cada petição ORDA enviada de uma máquina remota. Pode dirigir informação de histórico a uma memória ou a um arquivo em disco. O nome e rota desse arquivo pode ser escolhido.

    Como iniciar esse log:

    //para executar em uma máquina remota
    ds.startRequestLog(File("/PACKAGE/Logs/ordaLog.txt"))
    //também pode ser enviado a memória

    Se quiser usar esse número de sequência único em histórico de petições ORDA, necessita ativá-lo:

    //to be executed on a remote machine SET DATABASE PARAMETER(Client Log Recording;1)  
    //to enable log sequence number

    ds.startRequestLog(File("/PACKAGE/Logs/ordaLog.txt"))
    //can be also sent to memory SET DATABASE PARAMETER(Client Log Recording;0)
    //disabling sequence number

    Os campos abaixo são registrados para cada petição:

    Campo nomeDescriçãoExemplo
    sequenceNumberNúmero de operação único e sequencial da sessão de histórico104
    urlURL de petição de cliente ORDA"rest/Persons(30001)"
    startTimeData e hora de início utilizando o formato ISO 8601"2019-05-28T08:25:12.346Z"
    endTimeData e hora final usando formato ISO 8601"2019-05-28T08:25:12.371Z"
    durationDuração de processamento do cliente (ms)25
    responseObjeto resposta servidor{"status":200,"body":{"__entityModel":"Persons",[...]