Performance issues #4080

Warxcell · 2022-06-24T15:46:10Z

Warxcell
Jun 24, 2022

Hello I have some performance issues that I don't understand where they come from.

server responds for ~1 second. If there is a burst of requests - response is 200ms, but if there is no request for 5-10 seconds period, after that first request again takes 1 second.

Here profiler:

 [Shared libraries]:
   ticks  total  nonlib   name
   8613   65.1%          /usr/local/bin/node
    376    2.8%          /lib/x86_64-linux-gnu/libc-2.31.so
     33    0.2%          /usr/lib/x86_64-linux-gnu/libstdc++.so.6.0.28
     18    0.1%          [vdso]

ticks parent  name
   8613   65.1%  /usr/local/bin/node
   5261   61.1%    /usr/local/bin/node
   1595   30.3%      Function: ^compileFunction node:vm:309:25
   1590   99.7%        Function: ^wrapSafe node:internal/modules/cjs/loader:1024:18
   1587   99.8%          Function: ^Module._compile node:internal/modules/cjs/loader:1066:37
   1462   92.1%            Function: ^Module._extensions..js node:internal/modules/cjs/loader:1121:37
     57    3.6%            Function: ^m._compile /app/node_modules/@graphql-mesh/cli/node_modules/ts-node/dist/index.js:788:31
     53    3.3%            LazyCompile: ~m._compile /app/node_modules/@graphql-mesh/cli/node_modules/ts-node/dist/index.js:788:31
    417    7.9%      /usr/local/bin/node
    128   30.7%        LazyCompile: *field.resolve /app/node_modules/@envelop/core/index.js:41:33
    128  100.0%          /usr/local/bin/node
    128  100.0%            /usr/local/bin/node
     15    3.6%        LazyCompile: *handle /app/node_modules/graphql-jit/dist/ast.js:156:20
     11   73.3%          LazyCompile: *handle /app/node_modules/graphql-jit/dist/ast.js:156:20
     11  100.0%            LazyCompile: *handle /app/node_modules/graphql-jit/dist/ast.js:156:20
      4   26.7%          LazyCompile: *collectFieldsImpl /app/node_modules/graphql-jit/dist/ast.js:31:27
      4  100.0%            LazyCompile: *collectFieldsImpl /app/node_modules/graphql-jit/dist/ast.js:31:27
     15    3.6%        Function: ^quote /app/node_modules/json-bigint-patch/dist/index.js:183:19
     15  100.0%          Function: ^str /app/node_modules/json-bigint-patch/dist/index.js:200:17
     13   86.7%            Function: ^str /app/node_modules/json-bigint-patch/dist/index.js:200:17
      2   13.3%            Function: ^<anonymous> /app/node_modules/json-bigint-patch/dist/index.js:306:57
     15    3.6%        Function: ^onResolverMeasurement /app/node_modules/@envelop/core/index.js:953:28
     15  100.0%          Function: ^<anonymous> /app/node_modules/@envelop/core/index.js:1057:24
     15  100.0%            Function: ^field.resolve /app/node_modules/@envelop/core/index.js:41:33
     14    3.4%        LazyCompile: *joinShouldIncludeCompilations /app/node_modules/graphql-jit/dist/ast.js:208:39
     10   71.4%          Function: ^collectFieldsImpl /app/node_modules/graphql-jit/dist/ast.js:31:27
      6   60.0%            Function: ^collectFieldsImpl /app/node_modules/graphql-jit/dist/ast.js:31:27
      4   40.0%            LazyCompile: *_collectSubfields /app/node_modules/graphql-jit/dist/ast.js:393:27
      2   14.3%          LazyCompile: *collectFieldsImpl /app/node_modules/graphql-jit/dist/ast.js:31:27
      1   50.0%            LazyCompile: *_collectSubfields /app/node_modules/graphql-jit/dist/ast.js:393:27
      1   50.0%            Function: ^collectFields /app/node_modules/graphql-jit/dist/ast.js:23:23
      2   14.3%          Function: ^handle /app/node_modules/graphql-jit/dist/ast.js:156:20
      1   50.0%            LazyCompile: *collectFieldsImpl /app/node_modules/graphql-jit/dist/ast.js:31:27
      1   50.0%            LazyCompile: *augmentFieldNodeTree /app/node_modules/graphql-jit/dist/ast.js:147:30
     12    2.9%        LazyCompile: *onResolverMeasurement /app/node_modules/@envelop/core/index.js:953:28
     12  100.0%          Function: ^<anonymous> /app/node_modules/@envelop/core/index.js:1057:24
     12  100.0%            Function: ^field.resolve /app/node_modules/@envelop/core/index.js:41:33
     12    2.9%        Function: ^processTicksAndRejections node:internal/process/task_queues:67:35
     11    2.6%        LazyCompile: *visit /app/node_modules/graphql/language/visitor.js:102:15
      8   72.7%          Function: ^finalizeSelectionSet /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:545:30
      8  100.0%            Function: ^collectFragmentVariables /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:490:34
      1    9.1%          LazyCompile: *finalizeSelectionSet /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:545:30
      1  100.0%            Function: ^collectFragmentVariables /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:490:34
      1    9.1%          Function: ^prepareGatewayDocument /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:59:32
      1  100.0%            Function: ^transformRequest /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:936:21
      1    9.1%          Function: ^prefixRequest /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:112:23
      1  100.0%            Function: ^mergeRequests /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:58:23
     10    2.4%        LazyCompile: *<anonymous> /app/node_modules/graphql/type/definition.js:772:44
      8   80.0%          LazyCompile: *mapValue /app/node_modules/graphql/jsutils/mapValue.js:12:18
      8  100.0%            Function: ^defineFieldMap /app/node_modules/graphql/type/definition.js:765:24
      2   20.0%          LazyCompile: *toConfig /app/node_modules/graphql/type/definition.js:724:11
      1   50.0%            Function: ^rewireNamedType /app/node_modules/@graphql-tools/stitch/node_modules/@graphql-tools/utils/index.js:2240:29
      1   50.0%            Function: ^correctASTNodes /app/node_modules/@graphql-tools/stitch/node_modules/@graphql-tools/utils/index.js:2751:25
      9    2.2%        LazyCompile: *line /app/node_modules/generate-function/index.js:115:22
      5   55.6%          Function: ^compileObjectType /app/node_modules/graphql-jit/dist/execution.js:410:27
      4   80.0%            Function: ^compileType /app/node_modules/graphql-jit/dist/execution.js:340:21
      1   20.0%            LazyCompile: *compileType /app/node_modules/graphql-jit/dist/execution.js:340:21
      3   33.3%          Function: ^createResolveInfoThunk /app/node_modules/graphql-jit/dist/resolve-info.js:25:32
      3  100.0%            LazyCompile: *compileDeferredField /app/node_modules/graphql-jit/dist/execution.js:254:30
      1   11.1%          LazyCompile: *compileObjectType /app/node_modules/graphql-jit/dist/execution.js:410:27
      1  100.0%            LazyCompile: *compileType /app/node_modules/graphql-jit/dist/execution.js:340:21
      9    2.2%        LazyCompile: *collectFieldsImpl /app/node_modules/graphql-jit/dist/ast.js:31:27
      5   55.6%          LazyCompile: *collectFieldsImpl /app/node_modules/graphql-jit/dist/ast.js:31:27
      2   40.0%            LazyCompile: *collectFieldsImpl /app/node_modules/graphql-jit/dist/ast.js:31:27
      2   40.0%            Function: ^collectFields /app/node_modules/graphql-jit/dist/ast.js:23:23
      1   20.0%            LazyCompile: *_collectSubfields /app/node_modules/graphql-jit/dist/ast.js:393:27
      3   33.3%          LazyCompile: *_collectSubfields /app/node_modules/graphql-jit/dist/ast.js:393:27
      2   66.7%            LazyCompile: *memoized /app/node_modules/graphql-jit/dist/ast.js:406:22
      1   33.3%            Function: ^memoized /app/node_modules/graphql-jit/dist/ast.js:406:22
      1   11.1%          Function: ^collectFields /app/node_modules/graphql-jit/dist/ast.js:23:23
      1  100.0%            Function: ^_collectSubfields /app/node_modules/graphql-jit/dist/ast.js:393:27
      9    2.2%        Function: ^line /app/node_modules/generate-function/index.js:115:22
      4   44.4%          Function: ^compileObjectType /app/node_modules/graphql-jit/dist/execution.js:410:27
      4  100.0%            LazyCompile: *compileType /app/node_modules/graphql-jit/dist/execution.js:340:21
      3   33.3%          LazyCompile: *compileObjectType /app/node_modules/graphql-jit/dist/execution.js:410:27
      3  100.0%            LazyCompile: *compileType /app/node_modules/graphql-jit/dist/execution.js:340:21
      2   22.2%          Function: ^createResolveInfoThunk /app/node_modules/graphql-jit/dist/resolve-info.js:25:32
      2  100.0%            LazyCompile: *compileDeferredField /app/node_modules/graphql-jit/dist/execution.js:254:30
      8    1.9%        LazyCompile: ~quote /app/node_modules/json-bigint-patch/dist/index.js:183:19
      8  100.0%          LazyCompile: ~str /app/node_modules/json-bigint-patch/dist/index.js:200:17
      8  100.0%            LazyCompile: ~str /app/node_modules/json-bigint-patch/dist/index.js:200:17
      7    1.7%        LazyCompile: *executeFields /app/node_modules/graphql/execution/execute.js:407:23
      6   85.7%          Function: ^completeObjectValue /app/node_modules/graphql/execution/execute.js:877:29
      4   66.7%            LazyCompile: *completeValue /app/node_modules/graphql/execution/execute.js:576:23
      2   33.3%            Function: ^completeAbstractValue /app/node_modules/graphql/execution/execute.js:757:31
      1   14.3%          LazyCompile: *completeObjectValue /app/node_modules/graphql/execution/execute.js:877:29
      1  100.0%            Function: ^completeAbstractValue /app/node_modules/graphql/execution/execute.js:757:31
      5    1.2%        LazyCompile: *compileObjectType /app/node_modules/graphql-jit/dist/execution.js:410:27
      5  100.0%          LazyCompile: *compileType /app/node_modules/graphql-jit/dist/execution.js:340:21
      4   80.0%            Function: ^<anonymous> /app/node_modules/graphql-jit/dist/execution.js:528:14
      1   20.0%            LazyCompile: *compileDeferredField /app/node_modules/graphql-jit/dist/execution.js:254:30
      5    1.2%        Function: ^wrapSymbolTrackerToReportForContext /app/node_modules/@graphql-mesh/cli/node_modules/typescript/lib/typescript.js:51316:57
      5  100.0%          Function: ^withContext /app/node_modules/@graphql-mesh/cli/node_modules/typescript/lib/typescript.js:51282:33
      5  100.0%            Function: ^typeToTypeNode /app/node_modules/@graphql-mesh/cli/node_modules/typescript/lib/typescript.js:51254:42
      5    1.2%        Function: ^stat node:internal/modules/cjs/loader:151:14
      4   80.0%          Function: ^Module._findPath node:internal/modules/cjs/loader:500:28
      4  100.0%            Function: ^Module._resolveFilename node:internal/modules/cjs/loader:860:35
      1   20.0%          Function: ^tryFile node:internal/modules/cjs/loader:390:17
      1  100.0%            Function: ^tryExtensions node:internal/modules/cjs/loader:406:23
      5    1.2%        Function: ^readPackage node:internal/modules/cjs/loader:296:21
      3   60.0%          Function: ^readPackageScope node:internal/modules/cjs/loader:327:26
      2   66.7%            Function: ^trySelf node:internal/modules/cjs/loader:447:17
      1   33.3%            LazyCompile: ~trySelf node:internal/modules/cjs/loader:447:17
      1   20.0%          Function: ^tryPackage node:internal/modules/cjs/loader:344:20
      1  100.0%            LazyCompile: *Module._findPath node:internal/modules/cjs/loader:500:28
      1   20.0%          Function: ^resolveExports node:internal/modules/cjs/loader:478:24
      1  100.0%            Function: ^Module._findPath node:internal/modules/cjs/loader:500:28
    359    6.8%      LazyCompile: ~compileQuery /app/node_modules/graphql-jit/dist/execution.js:43:22
    221   61.6%        LazyCompile: ~jitExecutor /app/node_modules/@graphql-mesh/utils/index.js:468:32
    133   60.2%          LazyCompile: ~<anonymous> /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:358:59
    133  100.0%            /usr/local/bin/node
     88   39.8%          Function: ^<anonymous> /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:358:59
     88  100.0%            /usr/local/bin/node
    137   38.2%        Function: ^jitExecutor /app/node_modules/@graphql-mesh/utils/index.js:468:32
    137  100.0%          Function: ^<anonymous> /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:358:59
    137  100.0%            /usr/local/bin/node
    226    4.3%      Function: ^compileQuery /app/node_modules/graphql-jit/dist/execution.js:43:22
    226  100.0%        Function: ^jitExecutor /app/node_modules/@graphql-mesh/utils/index.js:468:32
    226  100.0%          Function: ^<anonymous> /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:358:59
    226  100.0%            /usr/local/bin/node
    112    2.1%      Function: ^handleWriteReq node:internal/stream_base_commons:45:24
    112  100.0%        Function: ^writeGeneric node:internal/stream_base_commons:147:22
    112  100.0%          Function: ^Socket._writeGeneric node:net:837:42
    112  100.0%            Function: ^Socket._write node:net:874:35
    112    2.1%      Function: ^compileForInternalLoader node:internal/bootstrap/loaders:316:27
    102   91.1%        Function: ^nativeModuleRequire node:internal/bootstrap/loaders:349:29
     18   17.6%          Function: ~<anonymous> node:repl:1:1
     18  100.0%            Function: ^compileForInternalLoader node:internal/bootstrap/loaders:316:27
     16   15.7%          LazyCompile: ~lazyUndici node:internal/bootstrap/pre_execution:186:22
     16  100.0%            LazyCompile: ~get node:internal/bootstrap/pre_execution:206:10
      8    7.8%          Function: ~<anonymous> node:http:1:1
      8  100.0%            Function: ^compileForInternalLoader node:internal/bootstrap/loaders:316:27
      7    6.9%          Function: ~<anonymous> node:tls:1:1
      7  100.0%            Function: ^compileForInternalLoader node:internal/bootstrap/loaders:316:27
      5    4.9%          LazyCompile: ~requireWithFallbackInDeps node:internal/bootstrap/loaders:363:35
      4   80.0%            LazyCompile: ~lib/client.js node:internal/deps/undici/undici:2677:18
      1   20.0%            LazyCompile: ~lib/core/util.js node:internal/deps/undici/undici:668:21
      5    4.9%          Function: ~<anonymous> node:internal/modules/esm/loader:1:1
      5  100.0%            Function: ^compileForInternalLoader node:internal/bootstrap/loaders:316:27
      5    4.9%          Function: ~<anonymous> node:_http_client:1:1
      5  100.0%            Function: ^compileForInternalLoader node:internal/bootstrap/loaders:316:27
      4    3.9%          LazyCompile: ~initializeCJSLoader node:internal/bootstrap/pre_execution:496:29
      4  100.0%            LazyCompile: ~prepareMainThreadExecution node:internal/bootstrap/pre_execution:31:36
      3    2.9%          LazyCompile: ~lazyWebCrypto node:crypto:125:23
      3  100.0%            LazyCompile: ~get node:crypto:369:8
      3    2.9%          Function: ^requireWithFallbackInDeps node:internal/bootstrap/loaders:363:35
      3  100.0%            LazyCompile: ~lib/fetch/index.js node:internal/deps/undici/undici:5434:23
      2    2.0%          LazyCompile: ~lazyLoadStreams node:fs:2907:25
      2  100.0%            LazyCompile: ~get ReadStream node:fs:3050:17
      2    2.0%          Function: ~<anonymous> node:worker_threads:1:1
      2  100.0%            Function: ^compileForInternalLoader node:internal/bootstrap/loaders:316:27
      2    2.0%          Function: ~<anonymous> node:internal/process/esm_loader:1:1
      2  100.0%            Function: ^compileForInternalLoader node:internal/bootstrap/loaders:316:27
      2    2.0%          Function: ~<anonymous> node:assert:1:1
      2  100.0%            Function: ^compileForInternalLoader node:internal/bootstrap/loaders:316:27
      2    2.0%          Function: ~<anonymous> node:_http_common:1:1
      2  100.0%            Function: ^compileForInternalLoader node:internal/bootstrap/loaders:316:27
     10    8.9%        Function: ^compileForPublicLoader node:internal/bootstrap/loaders:263:25
     10  100.0%          Function: ^loadNativeModule node:internal/modules/cjs/helpers:44:26
     10  100.0%            Function: ^Module._load node:internal/modules/cjs/loader:766:24
     82    1.6%      LazyCompile: ~compileOperation /app/node_modules/graphql-jit/dist/execution.js:185:26
     82  100.0%        LazyCompile: ~compileQuery /app/node_modules/graphql-jit/dist/execution.js:43:22
     56   68.3%          LazyCompile: ~jitExecutor /app/node_modules/@graphql-mesh/utils/index.js:468:32
     35   62.5%            LazyCompile: ~<anonymous> /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:358:59
     21   37.5%            Function: ^<anonymous> /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:358:59
     26   31.7%          Function: ^jitExecutor /app/node_modules/@graphql-mesh/utils/index.js:468:32
     26  100.0%            Function: ^<anonymous> /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:358:59
     81    1.5%      LazyCompile: *visit /app/node_modules/graphql/language/visitor.js:102:15
     35   43.2%        Function: ^print /app/node_modules/graphql/language/printer.js:18:15
     20   57.1%          Function: ^printWithCache /app/node_modules/@graphql-mesh/utils/index.js:440:62
     20  100.0%            Function: ^memoized /app/node_modules/@graphql-mesh/utils/node_modules/@graphql-tools/utils/index.js:516:29
     14   40.0%          LazyCompile: ~printWithCache /app/node_modules/@graphql-mesh/utils/index.js:440:62
      9   64.3%            Function: ^memoized /app/node_modules/@graphql-mesh/utils/node_modules/@graphql-tools/utils/index.js:516:29
      5   35.7%            LazyCompile: ~memoized /app/node_modules/@graphql-mesh/utils/node_modules/@graphql-tools/utils/index.js:516:29
      1    2.9%          LazyCompile: ~executor /app/node_modules/@graphql-mesh/graphql/node_modules/@graphql-tools/url-loader/index.js:428:26
      1  100.0%            LazyCompile: ~valueOrPromise.ValueOrPromise.then.result /app/node_modules/@graphql-mesh/graphql/node_modules/@graphql-tools/url-loader/index.js:551:62
     32   39.5%        Function: ^prepareGatewayDocument /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:59:32
     30   93.8%          Function: ^transformRequest /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:936:21
     30  100.0%            Function: ^delegateRequest /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:1158:25
      2    6.3%          LazyCompile: ~transformRequest /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:936:21
      2  100.0%            LazyCompile: ~delegateRequest /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:1158:25
      8    9.9%        Function: ^prefixRequest /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:112:23
      8  100.0%          Function: ^mergeRequests /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:58:23
      5   62.5%            Function: ^<anonymous> /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:358:59
      3   37.5%            LazyCompile: ~<anonymous> /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/batch-execute/index.js:358:59
      5    6.2%        Function: ^finalizeSelectionSet /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:545:30
      5  100.0%          Function: ^collectFragmentVariables /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:490:34
      5  100.0%            Function: ^finalizeGatewayDocument /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:364:33
      1    1.2%        LazyCompile: *finalizeSelectionSet /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:545:30
      1  100.0%          Function: ^collectFragmentVariables /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:490:34
      1  100.0%            Function: ^finalizeGatewayDocument /app/node_modules/@graphql-tools/wrap/node_modules/@graphql-tools/delegate/index.js:364:33
     75    1.4%      Function: ^slice node:buffer:597:12
     75  100.0%        Function: ^toString node:buffer:787:46
     75  100.0%          Function: ^readFileSync node:fs:464:22
     72   96.0%            Function: ^Module._extensions..js node:internal/modules/cjs/loader:1121:37
      2    2.7%            LazyCompile: ~Module._extensions..json node:internal/modules/cjs/loader:1171:39
      1    1.3%            LazyCompile: ~Module._extensions..js node:internal/modules/cjs/loader:1121:37

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Uh oh!

Performance issues #4080

Uh oh!

{{title}}

Uh oh!

Replies: 0 comments

Select a reply

Uh oh!

Performance issues #4080

Uh oh!

Warxcell Jun 24, 2022

Replies: 0 comments

Warxcell
Jun 24, 2022