Extreme memory increase due to infinite manual process

Questions
1

As the title states, we are experiencing some difficult to discover OOM issue’s that where not there before.

For some reason all manual started processes get stuck on an OOM. I can start them with the “execute workflow” button, which starts and (for some processes) finishes but as soon as i press the play button of a specific node, it executes the code until that node but the node itself never finishes and reaches an OOM error.

It all started when i tried to run the “orderList multiverse” node which sometime succeeds with an 5mb response after which it immediately crashes with OOM.

In the example below I started the flow pressing the playbutton at the right top of the “updateableVariables1” node on which it got stuck.
All workflows are turned off so there are no other workflows running (except for the webhook for Oauth, which is not triggered in this case until it would reach the “GetOauthMultivers1”, due to the way multivers works the normal oauth does not work )

the “code” node only contains a static response and should never get stuck or reach 8GB memory:

return [{json: {
  magentoCount: 0
}}];

Screenshot 2023-01-03 at 16.32.03
Screenshot 2023-01-03 at 16.32.03940×255 19.9 KB

Screenshot 2023-01-03 at 16.32.22

using the “execute workflow” leads to the next part but eventually gets killed due to OOM:

Screenshot 2023-01-03 at 16.43.11
Screenshot 2023-01-03 at 16.43.11716×272 18.4 KB

previously this all worked without any issue’s but we noticed today that for a couple of days al workflows got stopped due to these OOM’s

we are running this on our own server with the docker-compose setup
our .env contains these as an attempt to lessen the memory used

N8N_LOG_LEVEL=error
N8N_LOG_OUTPUT=file
N8N_LOG_FILE_MAXCOUNT=1
NODE_ENV=production
N8N_DEFAULT_BINARY_DATA_MODE=filesystem

the docker-compose contains:

version: "3"

services:
  traefik:
    image: "traefik"
    restart: always
    command:
      - "--api=true"
      - "--api.insecure=true"
      - "--providers.docker=true"
      - "--providers.docker.exposedbydefault=false"
      - "--entrypoints.web.address=:80"
      - "--entrypoints.web.http.redirections.entryPoint.to=websecure"
      - "--entrypoints.web.http.redirections.entryPoint.scheme=https"
      - "--entrypoints.websecure.address=:443"
      - "--certificatesresolvers.mytlschallenge.acme.tlschallenge=true"
      - "--certificatesresolvers.mytlschallenge.acme.email=${SSL_EMAIL}"
      - "--certificatesresolvers.mytlschallenge.acme.storage=/letsencrypt/acme.json"
    ports:
      - "80:80"
      - "443:443"
    volumes:
      - ${DATA_FOLDER}/letsencrypt:/letsencrypt
      - /var/run/docker.sock:/var/run/docker.sock:ro

  n8n:
    image: n8nio/n8n
    restart: always
    ports:
      - "127.0.0.1:5678:5678"
    labels:
      - traefik.enable=true
      - traefik.http.routers.n8n.rule=Host(`${SUBDOMAIN}.${DOMAIN_NAME}`)
      - traefik.http.routers.n8n.tls=true
      - traefik.http.routers.n8n.entrypoints=websecure
      - traefik.http.routers.n8n.tls.certresolver=mytlschallenge
      - traefik.http.middlewares.n8n.headers.SSLRedirect=true
      - traefik.http.middlewares.n8n.headers.STSSeconds=315360000
      - traefik.http.middlewares.n8n.headers.browserXSSFilter=true
      - traefik.http.middlewares.n8n.headers.contentTypeNosniff=true
      - traefik.http.middlewares.n8n.headers.forceSTSHeader=true
      - traefik.http.middlewares.n8n.headers.SSLHost=${DOMAIN_NAME}
      - traefik.http.middlewares.n8n.headers.STSIncludeSubdomains=true
      - traefik.http.middlewares.n8n.headers.STSPreload=true
    environment:
      - N8N_BASIC_AUTH_ACTIVE=true
      - N8N_BASIC_AUTH_USER
      - N8N_BASIC_AUTH_PASSWORD
      - N8N_HOST=${SUBDOMAIN}.${DOMAIN_NAME}
      - N8N_PORT=5678
      - N8N_PROTOCOL=https
      - N8N_LOG_LEVEL=error
      - N8N_LOG_OUTPUT=file
      - N8N_LOG_FILE_MAXCOUNT=1
      - NODE_ENV=production
      - WEBHOOK_TUNNEL_URL=https://${SUBDOMAIN}.${DOMAIN_NAME}/
      - EXECUTIONS_DATA_PRUNE=true
      - EXECUTIONS_DATA_MAX_AGE=72
#      - EXECUTIONS_PROCESS=main
      - N8N_DEFAULT_BINARY_DATA_MODE=filesystem
#      - EXECUTIONS_DATA_SAVE_ON_ERROR=all
#      - EXECUTIONS_DATA_SAVE_MANUAL_EXECUTIONS=false
#      - DB_SQLITE_VACUUM_ON_STARTUP=true
    volumes:
      - /var/run/docker.sock:/var/run/docker.sock
      - ${DATA_FOLDER}/.n8n:/home/node/.n8n
      - ${DATA_FOLDER}/n8n-files:/files

while the log_level is set to error there are also debug logs? these are the last 50 lines:

{"level":"debug","message":"Start processing node \"Function4\"","metadata":{"file":"WorkflowExecute.js","node":"Function4","timestamp":"2023-01-03T15:42:10.299Z","workflowId":"18"}}
{"level":"debug","message":"Running node \"Function4\" started","metadata":{"file":"WorkflowExecute.js","node":"Function4","timestamp":"2023-01-03T15:42:10.299Z","workflowId":"18"}}
{"level":"debug","message":"Running node \"Function4\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Function4","timestamp":"2023-01-03T15:42:10.309Z","workflowId":"18"}}
{"level":"debug","message":"Start processing node \"orderList multiverse\"","metadata":{"file":"WorkflowExecute.js","node":"orderList multiverse","timestamp":"2023-01-03T15:42:10.309Z","workflowId":"18"}}
{"level":"debug","message":"Running node \"orderList multiverse\" started","metadata":{"file":"WorkflowExecute.js","node":"orderList multiverse","timestamp":"2023-01-03T15:42:10.310Z","workflowId":"18"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2023-01-03T15:42:10.318Z"}}
{"level":"debug","message":"Received child process message of type processHook for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.241Z"}}
{"level":"debug","message":"Running node \"orderList multiverse\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"orderList multiverse","timestamp":"2023-01-03T15:42:35.241Z","workflowId":"18"}}
{"level":"debug","message":"Start processing node \"IF10\"","metadata":{"file":"WorkflowExecute.js","node":"IF10","timestamp":"2023-01-03T15:42:35.451Z","workflowId":"18"}}
{"level":"debug","message":"Received child process message of type processHook for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.553Z"}}
{"level":"debug","message":"Running node \"IF10\" started","metadata":{"file":"WorkflowExecute.js","node":"IF10","timestamp":"2023-01-03T15:42:35.452Z","workflowId":"18"}}
{"level":"debug","message":"Running node \"IF10\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"IF10","timestamp":"2023-01-03T15:42:35.464Z","workflowId":"18"}}
{"level":"debug","message":"Executing hook on node \"orderList multiverse\" (hookFunctionsPush)","metadata":{"executionId":"252235","file":"WorkflowExecuteAdditionalData.js","function":"nodeExecuteAfter","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.553Z","workflowId":"18"}}
{"level":"debug","message":"Send data of type \"nodeExecuteAfter\" to editor-UI","metadata":{"dataType":"nodeExecuteAfter","file":"Push.js","function":"send","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.554Z"}}
{"level":"debug","message":"Received child process message of type processHook for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.671Z"}}
{"level":"debug","message":"Executing hook on node \"IF10\" (hookFunctionsPush)","metadata":{"executionId":"252235","file":"WorkflowExecuteAdditionalData.js","function":"nodeExecuteBefore","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.672Z","workflowId":"18"}}
{"level":"debug","message":"Send data of type \"nodeExecuteBefore\" to editor-UI","metadata":{"dataType":"nodeExecuteBefore","file":"Push.js","function":"send","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.672Z"}}
{"level":"debug","message":"Start processing node \"GetOauthMultivers2\"","metadata":{"file":"WorkflowExecute.js","node":"GetOauthMultivers2","timestamp":"2023-01-03T15:42:35.716Z","workflowId":"18"}}
{"level":"debug","message":"Running node \"GetOauthMultivers2\" started","metadata":{"file":"WorkflowExecute.js","node":"GetOauthMultivers2","timestamp":"2023-01-03T15:42:35.717Z","workflowId":"18"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2023-01-03T15:42:35.719Z"}}
{"level":"debug","message":"Received child process message of type processHook for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.790Z"}}
{"level":"debug","message":"Executing hook on node \"IF10\" (hookFunctionsPush)","metadata":{"executionId":"252235","file":"WorkflowExecuteAdditionalData.js","function":"nodeExecuteAfter","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.791Z","workflowId":"18"}}
{"level":"debug","message":"Send data of type \"nodeExecuteAfter\" to editor-UI","metadata":{"dataType":"nodeExecuteAfter","file":"Push.js","function":"send","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.791Z"}}
{"level":"debug","message":"Received child process message of type processHook for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.868Z"}}
{"level":"debug","message":"Executing hook on node \"GetOauthMultivers2\" (hookFunctionsPush)","metadata":{"executionId":"252235","file":"WorkflowExecuteAdditionalData.js","function":"nodeExecuteBefore","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.868Z","workflowId":"18"}}
{"level":"debug","message":"Send data of type \"nodeExecuteBefore\" to editor-UI","metadata":{"dataType":"nodeExecuteBefore","file":"Push.js","function":"send","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.869Z"}}
{"level":"debug","message":"Received child process message of type sendMessageToUI for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.869Z"}}
{"level":"debug","message":"Send data of type \"sendConsoleMessage\" to editor-UI","metadata":{"dataType":"sendConsoleMessage","file":"Push.js","function":"send","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.869Z"}}
{"level":"debug","message":"Received webhook \"GET\" for path \"5f50a821-51b9-4e36-98ec-f5d276f1c3f0\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"executeWebhook","timestamp":"2023-01-03T15:42:35.881Z"}}
{"level":"info","message":"Initializing n8n process","metadata":{"file":"start.js","function":"run","timestamp":"2023-01-03T15:42:44.472Z"}}
{"level":"error","message":"Last session crashed","metadata":{"file":"CrashJournal.js","function":"init","timestamp":"2023-01-03T15:42:44.480Z"}}
{"level":"debug","message":"Lazy Loading credentials and nodes from n8n-nodes-base","metadata":{"credentials":310,"file":"DirectoryLoader.js","function":"loadAll","nodes":389,"timestamp":"2023-01-03T15:42:55.809Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:42:56.062Z"}}
{"level":"debug","message":"[license] initializing for deviceFingerprint 894cf08c5e93f1e0fe9d141c18deb2a60f7fc8dedf12e32b02eb6c5be667d415","metadata":{"file":"LicenseManager.js","function":"log","timestamp":"2023-01-03T15:42:57.145Z"}}
{"level":"debug","message":"Initializing active workflow \"Multivers OAuth process\" (startup)","metadata":{"file":"ActiveWorkflowRunner.js","function":"init","timestamp":"2023-01-03T15:42:57.674Z","workflowId":15,"workflowName":"Multivers OAuth process"}}
{"level":"debug","message":"Add editor-UI session","metadata":{"file":"Push.js","function":"add","sessionId":"pxssqc326p8","timestamp":"2023-01-03T15:42:57.871Z"}}
{"level":"debug","message":"Add editor-UI session","metadata":{"file":"Push.js","function":"add","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:57.877Z"}}
{"level":"verbose","message":"Successfully started workflow \"Multivers OAuth process\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"init","timestamp":"2023-01-03T15:42:57.923Z","workflowId":15,"workflowName":"Multivers OAuth process"}}
{"level":"verbose","message":"Finished initializing active workflows (startup)","metadata":{"file":"ActiveWorkflowRunner.js","function":"init","timestamp":"2023-01-03T15:42:57.925Z"}}
{"level":"debug","message":"Add editor-UI session","metadata":{"file":"Push.js","function":"add","sessionId":"r7eql1zebsc","timestamp":"2023-01-03T15:42:59.282Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:43:56.080Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:44:56.096Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:45:56.117Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:46:56.136Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:47:56.156Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:48:56.176Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:49:56.194Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:50:56.214Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:51:56.234Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:52:56.254Z"}}

n8n version: 0.209.4

Does someone know how to solve these OOM?

2

Hey @Joey_Geraeds,

Can you share the settings.json file with anything sensitive replaced with something like ?

3

sure here you go @Jon
the magentoLoadPerPage is set low for testing, usually it is set to 100~300

{
  "magentoLoadPerPage": 10,
  "baseUrl": "https://some api url /WebApi/",
  "database": "db name",
  "magentoAuth": "bearer token",
  "magentoBaseUrl": "http://site url",
  "magentoBaseBulkUrl": "http://site url but for bulk requests",
  "basePath": "/base/path/for/files",
  "imageServer": "some.domain.com/for/images",
  "multiversOauth": "https://url.for.webhook/webhook/",
  "multiversRefresh": "https://url.for.webhook/webhook/"
}
4

Hey @Joey_Geraeds,

Out of interest if you disable everything after the code node in the workflow do you see the same issue?

5

Do you mean after the node that is started by the top right “play” button? Which in this case was the “code” node. Or do you specifically mean the code node in this case?

I’m away from my laptop but i can and will test them both tomorrow

6

Assuming updateableVariables1 is the node that is triggering the OOM disabling everything after that would either confirm this or point towards something else.

7

Well yes thats what i suppose as well, but the remaining issue is that it is not exacly the "code"node… its every node that is started with the “play/start” button above it. So it is the “last” node that should execute and show response

8

Ah I get you, So you hit this issue when you run each one at a time rather than the workflow together? I will see if I can come up with a way to reproduce it so we can look into it in more detail.

9

Yes exactly, I’ve added the video’s to show you whats happening;
https://www.awesomescreenshot.com/video/13729865?key=306c63d108c79bf4ff13e8c5d25e672b

However it also uses more memory than before if we start it with the execute workflow button.
With the execute button it gives the response and “stops” the flow, yet sometimes due to the HTTP node it also receives an OOM on an 5mb response (the http node on which it hangs in the video calls a webhook but the oom is starting from the orderlist multiverse node)
https://www.awesomescreenshot.com/video/13730023?key=a5bbd05395bf57ad693f769eee70dd8c

10

When you say more memory than before do you remember what version you was using? That might help us track down the changes.

11

So I have quickly added your settings.json and ran the workflow like you have in the first video and for some reason I am not seeing the same issue :thinking:

Do you have more data in your setting json file? When you get the OOM message is this something you are seeing in the logs or in the browser?

12

sadly thats all there is that are all the variables within the file.
The previous version we used was also the actual previous released version there was only one update open yesterday. And before that update we had less then 10 updates open, but that was a quite some time ago, before this problem started. This started somewhere in the last 2 weeks during the holidays so there where no other changes / or updates done to it

13

Do you get the same issue if you remake the workflow? Where are you seeing the OOM message as well?

@netroy do you think your test build would show anything here?

14

The custom docker image can help narrow down if there is a memory leak.

@Joey_Geraeds Can you please try running this docker image: n8nio/n8n:debug-memory-usage, and share the console output with us?

1 Like
15

Yes, remaking and copying results in the same, even if it are just parts instead of the entire workflow

16

Making it locally does not result in any issue’s it seems as it is related to the specific image/server

17

Can you share the server logs? I suspect the issue might now be happening locally because this custom image is trying to free up as much memory as possible after every node execution. The logs would show us if that’s what’s helping the workflow execute locally without issues.

18

I think you mean these, or do you mean the actual server logs?:

{"level":"debug","message":"Start processing node \"Function4\"","metadata":{"file":"WorkflowExecute.js","node":"Function4","timestamp":"2023-01-03T15:42:10.299Z","workflowId":"18"}}
{"level":"debug","message":"Running node \"Function4\" started","metadata":{"file":"WorkflowExecute.js","node":"Function4","timestamp":"2023-01-03T15:42:10.299Z","workflowId":"18"}}
{"level":"debug","message":"Running node \"Function4\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Function4","timestamp":"2023-01-03T15:42:10.309Z","workflowId":"18"}}
{"level":"debug","message":"Start processing node \"orderList multiverse\"","metadata":{"file":"WorkflowExecute.js","node":"orderList multiverse","timestamp":"2023-01-03T15:42:10.309Z","workflowId":"18"}}
{"level":"debug","message":"Running node \"orderList multiverse\" started","metadata":{"file":"WorkflowExecute.js","node":"orderList multiverse","timestamp":"2023-01-03T15:42:10.310Z","workflowId":"18"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2023-01-03T15:42:10.318Z"}}
{"level":"debug","message":"Received child process message of type processHook for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.241Z"}}
{"level":"debug","message":"Running node \"orderList multiverse\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"orderList multiverse","timestamp":"2023-01-03T15:42:35.241Z","workflowId":"18"}}
{"level":"debug","message":"Start processing node \"IF10\"","metadata":{"file":"WorkflowExecute.js","node":"IF10","timestamp":"2023-01-03T15:42:35.451Z","workflowId":"18"}}
{"level":"debug","message":"Received child process message of type processHook for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.553Z"}}
{"level":"debug","message":"Running node \"IF10\" started","metadata":{"file":"WorkflowExecute.js","node":"IF10","timestamp":"2023-01-03T15:42:35.452Z","workflowId":"18"}}
{"level":"debug","message":"Running node \"IF10\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"IF10","timestamp":"2023-01-03T15:42:35.464Z","workflowId":"18"}}
{"level":"debug","message":"Executing hook on node \"orderList multiverse\" (hookFunctionsPush)","metadata":{"executionId":"252235","file":"WorkflowExecuteAdditionalData.js","function":"nodeExecuteAfter","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.553Z","workflowId":"18"}}
{"level":"debug","message":"Send data of type \"nodeExecuteAfter\" to editor-UI","metadata":{"dataType":"nodeExecuteAfter","file":"Push.js","function":"send","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.554Z"}}
{"level":"debug","message":"Received child process message of type processHook for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.671Z"}}
{"level":"debug","message":"Executing hook on node \"IF10\" (hookFunctionsPush)","metadata":{"executionId":"252235","file":"WorkflowExecuteAdditionalData.js","function":"nodeExecuteBefore","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.672Z","workflowId":"18"}}
{"level":"debug","message":"Send data of type \"nodeExecuteBefore\" to editor-UI","metadata":{"dataType":"nodeExecuteBefore","file":"Push.js","function":"send","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.672Z"}}
{"level":"debug","message":"Start processing node \"GetOauthMultivers2\"","metadata":{"file":"WorkflowExecute.js","node":"GetOauthMultivers2","timestamp":"2023-01-03T15:42:35.716Z","workflowId":"18"}}
{"level":"debug","message":"Running node \"GetOauthMultivers2\" started","metadata":{"file":"WorkflowExecute.js","node":"GetOauthMultivers2","timestamp":"2023-01-03T15:42:35.717Z","workflowId":"18"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2023-01-03T15:42:35.719Z"}}
{"level":"debug","message":"Received child process message of type processHook for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.790Z"}}
{"level":"debug","message":"Executing hook on node \"IF10\" (hookFunctionsPush)","metadata":{"executionId":"252235","file":"WorkflowExecuteAdditionalData.js","function":"nodeExecuteAfter","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.791Z","workflowId":"18"}}
{"level":"debug","message":"Send data of type \"nodeExecuteAfter\" to editor-UI","metadata":{"dataType":"nodeExecuteAfter","file":"Push.js","function":"send","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.791Z"}}
{"level":"debug","message":"Received child process message of type processHook for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.868Z"}}
{"level":"debug","message":"Executing hook on node \"GetOauthMultivers2\" (hookFunctionsPush)","metadata":{"executionId":"252235","file":"WorkflowExecuteAdditionalData.js","function":"nodeExecuteBefore","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.868Z","workflowId":"18"}}
{"level":"debug","message":"Send data of type \"nodeExecuteBefore\" to editor-UI","metadata":{"dataType":"nodeExecuteBefore","file":"Push.js","function":"send","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.869Z"}}
{"level":"debug","message":"Received child process message of type sendMessageToUI for execution ID 252235.","metadata":{"executionId":"252235","file":"WorkflowRunner.js","timestamp":"2023-01-03T15:42:35.869Z"}}
{"level":"debug","message":"Send data of type \"sendConsoleMessage\" to editor-UI","metadata":{"dataType":"sendConsoleMessage","file":"Push.js","function":"send","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:35.869Z"}}
{"level":"debug","message":"Received webhook \"GET\" for path \"5f50a821-51b9-4e36-98ec-f5d276f1c3f0\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"executeWebhook","timestamp":"2023-01-03T15:42:35.881Z"}}
{"level":"info","message":"Initializing n8n process","metadata":{"file":"start.js","function":"run","timestamp":"2023-01-03T15:42:44.472Z"}}
{"level":"error","message":"Last session crashed","metadata":{"file":"CrashJournal.js","function":"init","timestamp":"2023-01-03T15:42:44.480Z"}}
{"level":"debug","message":"Lazy Loading credentials and nodes from n8n-nodes-base","metadata":{"credentials":310,"file":"DirectoryLoader.js","function":"loadAll","nodes":389,"timestamp":"2023-01-03T15:42:55.809Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:42:56.062Z"}}
{"level":"debug","message":"[license] initializing for deviceFingerprint 894cf08c5e93f1e0fe9d141c18deb2a60f7fc8dedf12e32b02eb6c5be667d415","metadata":{"file":"LicenseManager.js","function":"log","timestamp":"2023-01-03T15:42:57.145Z"}}
{"level":"debug","message":"Initializing active workflow \"Multivers OAuth process\" (startup)","metadata":{"file":"ActiveWorkflowRunner.js","function":"init","timestamp":"2023-01-03T15:42:57.674Z","workflowId":15,"workflowName":"Multivers OAuth process"}}
{"level":"debug","message":"Add editor-UI session","metadata":{"file":"Push.js","function":"add","sessionId":"pxssqc326p8","timestamp":"2023-01-03T15:42:57.871Z"}}
{"level":"debug","message":"Add editor-UI session","metadata":{"file":"Push.js","function":"add","sessionId":"6mas4b5yk7m","timestamp":"2023-01-03T15:42:57.877Z"}}
{"level":"verbose","message":"Successfully started workflow \"Multivers OAuth process\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"init","timestamp":"2023-01-03T15:42:57.923Z","workflowId":15,"workflowName":"Multivers OAuth process"}}
{"level":"verbose","message":"Finished initializing active workflows (startup)","metadata":{"file":"ActiveWorkflowRunner.js","function":"init","timestamp":"2023-01-03T15:42:57.925Z"}}
{"level":"debug","message":"Add editor-UI session","metadata":{"file":"Push.js","function":"add","sessionId":"r7eql1zebsc","timestamp":"2023-01-03T15:42:59.282Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:43:56.080Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:44:56.096Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:45:56.117Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:46:56.136Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:47:56.156Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:48:56.176Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:49:56.194Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:50:56.214Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:51:56.234Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getWaitingExecutions","timestamp":"2023-01-03T15:52:56.254Z"}}
19

yeah, I meant the actual output of the container using docker log

20

That does not show anything, sure all thats in has been added after i started the flow… which is quite weird since this flow “Multivers OAuth” is not called from this flow YET, this is actually the next node which is after the “code” node;

n8n ready on 0.0.0.0, port 5678
Version: 0.209.4
 ================================
   Start Active Workflows:
 ================================
   - Multivers OAuth process (ID: 15)
     => Started

Editor is now accessible via:
https://url.com:5678/
n8n ready on 0.0.0.0, port 5678
Version: 0.209.4
 ================================
   Start Active Workflows:
 ================================
   - Multivers OAuth process (ID: 15)
     => Started

Editor is now accessible via:
https://url.com:5678/
n8n ready on 0.0.0.0, port 5678
Version: 0.209.4
 ================================
   Start Active Workflows:
 ================================
   - Multivers OAuth process (ID: 15)
     => Started

Editor is now accessible via:
https://url.com:5678/
n8n ready on 0.0.0.0, port 5678
Version: 0.209.4
 ================================
   Start Active Workflows:
 ================================
   - Multivers OAuth process (ID: 15)
     => Started

Editor is now accessible via:
https://url.com:5678/