node_profiling_example

Exemplo de perfil de nodejs

Exemplo de uso do Profiler NodeJS integrado. Não melhorei o desempenho, mas aprendi algumas coisas.

O que está sendo perfilado

Construindo um índice de pesquisa de texto completo de arquivos de texto em um sistema de arquivos local, usando LUNR. Atualmente, leva cerca de 3 segundos para indexar 1000 arquivos.

Perfil do aplicativo

./profile.sh

Isso produz um arquivo profile_output.txt . Consulte a seção 'Reading the NodeJS Profiler Saída' abaixo para como interpretar esse arquivo.

Identifique e corrija problemas de desempenho

Podemos ver que 81% do tempo de processo é gasto em 'bibliotecas compartilhadas', 77/81, sendo Node.exe:

 [Summary]:
   ticks  total  nonlib   name
    315   18.6%   96.6%  JavaScript
      0    0.0%    0.0%  C++
     80    4.7%   24.5%  GC
   1369   80.8%          Shared libraries
     11    0.6%          Unaccounted

 [Shared libraries]:
   ticks  total  nonlib   name
   1303   76.9%          C:Program Filesnodejsnode.exe
     65    3.8%          C:WINDOWSSYSTEM32ntdll.dll
      1    0.1%          C:WINDOWSSystem32KERNEL32.DLL

Usando este visualizador de perfil on -line, parece que a maioria das chamadas para o nó é devida ao construtor.Add, o que é um pouco confuso. Eu acho que isso pode ser devido ao ASYNC READFile Calling Add, que é 'culpar' Builder.add pelo tempo gasto lendo arquivos no sistema de arquivos. Vamos separar as duas operações.

Separando a leitura de arquivos da construção do índice LUNR

Acontece que a leitura de arquivos é muito rápida. A maior parte do tempo do aplicativo é gasta no construtor. No entanto, Builder.add chama Node.exe, além da qual não obtemos informações.

pendência

• Spy-js é útil? Eu tentei por alguns minutos e só consegui me dizer 'sim, o construtor.Add é chamado muito'.

• Existe uma maneira de ver mais sobre o que está sendo chamado? E por quê?

---

Interpretando a saída do Profiler NodeJS

O Profiler padrão é um perfil de amostragem, o que significa que registra o ponteiro de instruções atual em determinados intervalos.

Glossário

• Ticks: Número de locais de ponteiro de instrução gravados
• Não contabilizado: Número de locais de ponteiro de instrução que não podiam ser rastreados até uma função conhecida
• não -lib :?
• LazyCompile: A função foi compilada preguiçosamente. Não é o número de carrapatos gastos no compilador!
• *: Função otimizada
• ~: função não otimizada

Seção para cima para cima

• Leia as linhas recuadas como "foi chamado por" a linha dos pais
• Eu recomendo uma representação visual sobre isso

Visualizando a saída do Profiler

Existem várias ferramentas, aqui está uma prática on -line: https://mapbox.github.io/flamebear/# arraste o arquivo profile_output.json nesse site para visualizar os dados do Profiler.

Referências

• Nodejs Docs
• Cara explica a saída do Profiler
• V8 Docs Profiler
• Visualizador de perfil on -line