When using "execute workflow" node I get stuck at "Proxying request to axios"

samsamthewise · October 13, 2022, 9:14pm

I’ve built a set of workflows that I need to trigger one after the other. I have a cron-node in the first workflow which ends with a “execute workflow” node that triggers the next workflow and so on.

The whole thing ran fine to begin with, but at some point, it stopped working. I’ve been running it manually since, but today I enabled logging to debug.

The log seems to be stuck at

{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-13T20:51:32.769Z"}}

I’ve experienced this across several workflows, and I know for sure the workflows run fine separately, when I manually run them.

Any help would be hugely appreciated.

Information on n8n setup

n8n 0.197.1
SQLite
Docker

Jon · October 14, 2022, 7:49am

Hey @samsamthewise,

What are the worklfows doing? Does the execution log show anything?

samsamthewise · October 14, 2022, 12:34pm

I’m pulling data from a Rest API and a GraphQL api across 10 workflows. It’s not a massive amount of data at all.

As I said the whole thing used to run fine for a week or two, and then it didn’t. Unfortunately, i didn’t have time to debug when it stopped working, so had to run manually until now, so it’s hard to pinpoint what went wrong.

Execution log just prints the message in my post “Proxying request to axios” every second or so, and seems to keep going forever. The workflow that it’s trying to run is a super simple workflow that takes seconds to run.

Please, do let me know of whatever info I can provide to make debugging easier.

Jon · October 14, 2022, 2:19pm

Hey @samsamthewise,

The Execution Log shouldn’t show that Just to confirm you are using the execution log in the application and not the log from the container right? It would be good to know if you see this issue as soon as you load the page or if it takes some time.

samsamthewise · October 18, 2022, 9:21am

I’ve enabled -e variable N8N_LOG_LEVEL, which I suppose is what you call “log from container”?

I’ve also enabled “progress log” with -e EXECUTIONS_DATA_SAVE_ON_PROGRESS, just now.

This is what the in-app execution log looks like when running.

In this specific case the “instafilm monday.userdata” had been executing for over an hour, but it’s like a 10 second workflow when I run it manually without triggering new workflows.

All the workflows run perfectly when I run them manually.

All workflows:

Request data from an API endpoint
Makes some transformations in set-nodes and item list nodes etc.
Inserts result table into SQL database
triggers next workflow

samsamthewise · October 24, 2022, 7:56am

@n8n_Team I’m still having big issues resolving this, any help would be greatly appreciated.

I’m making REST API calls & GraphQL calls.

I also have a Pi-hole container on my server, but I tried removing it to make sure there wasn’t any limitations being imposed from the pi-hole container.

Today I tried rolling back to n8n v. 0.190.0, which didn’t help either.

Also, I experienced a succesful run of two of the workflows automatically triggered, but they took something like 2 hours to succeed. Mind you, when I run the workflows manually, they should take minutes to complete.

Any guidance on how to provide information, I’ll do my best, although I’m not natively (finance guy) from a software background, so bear with me.

Thanks in advance.

Jon · October 24, 2022, 10:37am

Hey @samsamthewise,

If you open one of those execution logs it should show where it is stopping, If we can get a screenshot of that it would be a big help.

What are you running n8n on? Can you also share the full debug log from the container as well? I suspect it is going to be a resource issue or something funky in the config but logs are always the best way to tell.

What are you using to trigger the workflows as well is it an interval / cron or something else?

samsamthewise · October 24, 2022, 12:23pm

Can you help me understand the different logs.

I’m running the environment variables from:

logging
https://docs.n8n.io/hosting/logging/
and
execution data
https://docs.n8n.io/hosting/environment-variables/#executions

(Save on error, save on success etc, including pruning variables.)

I’m running n8n on a local Unraid server, which from what I understand is linux distro “slackware”.

Cron-node triggers the first workflow which ends with an “execute workflow”-node that triggers the next workflow and so on.

Jon · October 24, 2022, 2:14pm

Hey @samsamthewise,

So the Execution Log is what you see in the editor, This shows us the data going through the nodes and the workflow itself.

The other log is the application log which shows us more about what is happening internally when N8N_LOG_LEVEL is set.

Unraid should be ok as a platform so it may come down to resources there but we should see some kind of sign in the log if that is the case.

samsamthewise · October 25, 2022, 12:24pm

I’m having a hard time explaining.

Workflow 1 is triggered with a cron-node →
Workflow 1 is processed and completed, last node triggers an integrated execution of workflow 2 →
Workflow 2 should take like, 1 minute, but keeps going on forever.

If I manually execute workflow 2 (or any other of the workflows), it runs without problems and within a minute.

The fact that it goes on forever means I’m not getting an error in the UI.

Just now, I let it run for around 90 minutes and then extracted the application log.

Notice that I’ve cut out a big part of the log, which is just repetitive “Proxying request to axios”. Check the timestamp difference.

{"level":"info","message":"Initializing n8n process","metadata":{"file":"start.js","timestamp":"2022-10-25T10:32:01.038Z"}}
{"level":"debug","message":"No codex available for: N8nTrainingCustomerDatastore.node.js","metadata":{"file":"LoadNodesAndCredentials.js","function":"addCodex","timestamp":"2022-10-25T10:32:05.077Z"}}
{"level":"debug","message":"No codex available for: N8nTrainingCustomerMessenger.node.js","metadata":{"file":"LoadNodesAndCredentials.js","function":"addCodex","timestamp":"2022-10-25T10:32:05.078Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getwaitingExecutions","timestamp":"2022-10-25T10:32:06.988Z"}}
{"level":"debug","message":"Initializing active workflow \"instafilm economic.posteringer\" (startup)","metadata":{"file":"ActiveWorkflowRunner.js","function":"init","timestamp":"2022-10-25T10:32:07.680Z","workflowId":1,"workflowName":"instafilm economic.posteringer"}}
{"level":"verbose","message":"Successfully activated workflow \"instafilm economic.posteringer\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"add","timestamp":"2022-10-25T10:32:07.695Z","workflowId":"1","workflowName":"instafilm economic.posteringer"}}
{"level":"verbose","message":"Successfully started workflow \"instafilm economic.posteringer\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"init","timestamp":"2022-10-25T10:32:07.696Z","workflowId":1,"workflowName":"instafilm economic.posteringer"}}
{"level":"verbose","message":"Finished initializing active workflows (startup)","metadata":{"file":"ActiveWorkflowRunner.js","function":"init","timestamp":"2022-10-25T10:32:07.696Z"}}
{"level":"debug","message":"Add editor-UI session","metadata":{"file":"Push.js","function":"add","sessionId":"zicp4vu09o","timestamp":"2022-10-25T10:32:11.690Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getwaitingExecutions","timestamp":"2022-10-25T10:33:07.007Z"}}
{"level":"verbose","message":"Successfully deactivated workflow \"1\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"remove","timestamp":"2022-10-25T10:33:34.053Z","workflowId":"1"}}
{"level":"verbose","message":"Successfully activated workflow \"instafilm economic.posteringer\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"add","timestamp":"2022-10-25T10:33:34.141Z","workflowId":"1","workflowName":"instafilm economic.posteringer"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getwaitingExecutions","timestamp":"2022-10-25T10:34:07.019Z"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getwaitingExecutions","timestamp":"2022-10-25T10:35:07.036Z"}}
{"level":"verbose","message":"Successfully deactivated workflow \"1\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"remove","timestamp":"2022-10-25T10:35:33.548Z","workflowId":"1"}}
{"level":"verbose","message":"Successfully activated workflow \"instafilm economic.posteringer\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"add","timestamp":"2022-10-25T10:35:33.611Z","workflowId":"1","workflowName":"instafilm economic.posteringer"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getwaitingExecutions","timestamp":"2022-10-25T10:36:07.050Z"}}
{"level":"verbose","message":"Successfully deactivated workflow \"1\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"remove","timestamp":"2022-10-25T10:36:30.881Z","workflowId":"1"}}
{"level":"verbose","message":"Successfully activated workflow \"instafilm economic.posteringer\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"add","timestamp":"2022-10-25T10:36:30.942Z","workflowId":"1","workflowName":"instafilm economic.posteringer"}}
{"level":"debug","message":"Wait tracker querying database for waiting executions","metadata":{"file":"WaitTracker.js","function":"getwaitingExecutions","timestamp":"2022-10-25T10:37:07.060Z"}}
{"level":"debug","message":"Received trigger for workflow \"instafilm economic.posteringer\"","metadata":{"file":"ActiveWorkflowRunner.js","function":"returnFunctions.emit","timestamp":"2022-10-25T10:37:26.003Z"}}
{"level":"verbose","message":"Execution for workflow instafilm economic.posteringer was assigned id 11","metadata":{"executionId":"11","file":"WorkflowRunner.js","function":"runMainProcess","timestamp":"2022-10-25T10:37:26.065Z"}}
{"level":"verbose","message":"Execution for workflow instafilm economic.posteringer was assigned id 11","metadata":{"executionId":"11","file":"WorkflowRunner.js","function":"runMainProcess","timestamp":"2022-10-25T10:37:26.065Z"}}
{"level":"debug","message":"Execution ID 11 had Execution data. Running with payload.","metadata":{"executionId":"11","file":"WorkflowRunner.js","function":"runMainProcess","timestamp":"2022-10-25T10:37:26.067Z"}}
{"level":"verbose","message":"Workflow execution started","metadata":{"file":"WorkflowExecute.js","function":"processRunExecutionData","timestamp":"2022-10-25T10:37:26.068Z","workflowId":1}}
{"level":"debug","message":"Executing hook (hookFunctionsPush)","metadata":{"executionId":"11","file":"WorkflowExecuteAdditionalData.js","function":"workflowExecuteBefore","timestamp":"2022-10-25T10:37:26.071Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"Cron\"","metadata":{"file":"WorkflowExecute.js","node":"Cron","timestamp":"2022-10-25T10:37:26.072Z","workflowId":1}}
{"level":"debug","message":"Running node \"Cron\" started","metadata":{"file":"WorkflowExecute.js","node":"Cron","timestamp":"2022-10-25T10:37:26.072Z","workflowId":1}}
{"level":"debug","message":"Running node \"Cron\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Cron","timestamp":"2022-10-25T10:37:26.072Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"GET accounts\"","metadata":{"file":"WorkflowExecute.js","node":"GET accounts","timestamp":"2022-10-25T10:37:26.073Z","workflowId":1}}
{"level":"debug","message":"Running node \"GET accounts\" started","metadata":{"file":"WorkflowExecute.js","node":"GET accounts","timestamp":"2022-10-25T10:37:26.075Z","workflowId":1}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:26.121Z"}}
{"level":"debug","message":"Running node \"GET accounts\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"GET accounts","timestamp":"2022-10-25T10:37:26.311Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"Item Lists\"","metadata":{"file":"WorkflowExecute.js","node":"Item Lists","timestamp":"2022-10-25T10:37:26.311Z","workflowId":1}}
{"level":"debug","message":"Running node \"Item Lists\" started","metadata":{"file":"WorkflowExecute.js","node":"Item Lists","timestamp":"2022-10-25T10:37:26.311Z","workflowId":1}}
{"level":"debug","message":"Running node \"Item Lists\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Item Lists","timestamp":"2022-10-25T10:37:26.313Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"GET accounts pages\"","metadata":{"file":"WorkflowExecute.js","node":"GET accounts pages","timestamp":"2022-10-25T10:37:26.313Z","workflowId":1}}
{"level":"debug","message":"Running node \"GET accounts pages\" started","metadata":{"file":"WorkflowExecute.js","node":"GET accounts pages","timestamp":"2022-10-25T10:37:26.313Z","workflowId":1}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:26.316Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:26.316Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:26.316Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:26.317Z"}}
{"level":"debug","message":"Running node \"GET accounts pages\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"GET accounts pages","timestamp":"2022-10-25T10:37:29.435Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"Pages\"","metadata":{"file":"WorkflowExecute.js","node":"Pages","timestamp":"2022-10-25T10:37:29.436Z","workflowId":1}}
{"level":"debug","message":"Running node \"Pages\" started","metadata":{"file":"WorkflowExecute.js","node":"Pages","timestamp":"2022-10-25T10:37:29.436Z","workflowId":1}}
{"level":"debug","message":"Running node \"Pages\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Pages","timestamp":"2022-10-25T10:37:29.438Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"Invoice Pages List\"","metadata":{"file":"WorkflowExecute.js","node":"Invoice Pages List","timestamp":"2022-10-25T10:37:29.438Z","workflowId":1}}
{"level":"debug","message":"Running node \"Invoice Pages List\" started","metadata":{"file":"WorkflowExecute.js","node":"Invoice Pages List","timestamp":"2022-10-25T10:37:29.439Z","workflowId":1}}
{"level":"debug","message":"Running node \"Invoice Pages List\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Invoice Pages List","timestamp":"2022-10-25T10:37:29.472Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"Set\"","metadata":{"file":"WorkflowExecute.js","node":"Set","timestamp":"2022-10-25T10:37:29.473Z","workflowId":1}}
{"level":"debug","message":"Running node \"Set\" started","metadata":{"file":"WorkflowExecute.js","node":"Set","timestamp":"2022-10-25T10:37:29.473Z","workflowId":1}}
{"level":"debug","message":"Running node \"Set\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Set","timestamp":"2022-10-25T10:37:29.475Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"Invoice ListFromZero\"","metadata":{"file":"WorkflowExecute.js","node":"Invoice ListFromZero","timestamp":"2022-10-25T10:37:29.475Z","workflowId":1}}
{"level":"debug","message":"Running node \"Invoice ListFromZero\" started","metadata":{"file":"WorkflowExecute.js","node":"Invoice ListFromZero","timestamp":"2022-10-25T10:37:29.476Z","workflowId":1}}
{"level":"debug","message":"Running node \"Invoice ListFromZero\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Invoice ListFromZero","timestamp":"2022-10-25T10:37:29.476Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"GET Posteringer\"","metadata":{"file":"WorkflowExecute.js","node":"GET Posteringer","timestamp":"2022-10-25T10:37:29.477Z","workflowId":1}}
{"level":"debug","message":"Running node \"GET Posteringer\" started","metadata":{"file":"WorkflowExecute.js","node":"GET Posteringer","timestamp":"2022-10-25T10:37:29.477Z","workflowId":1}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.488Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.488Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.488Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.488Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.489Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.489Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.489Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.489Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.489Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.490Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.490Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.490Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.490Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.491Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.491Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.491Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.491Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.492Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.492Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.492Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.492Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.492Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.493Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:29.493Z"}}
{"level":"debug","message":"Running node \"GET Posteringer\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"GET Posteringer","timestamp":"2022-10-25T10:37:32.194Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"Posteringer ToList\"","metadata":{"file":"WorkflowExecute.js","node":"Posteringer ToList","timestamp":"2022-10-25T10:37:32.194Z","workflowId":1}}
{"level":"debug","message":"Running node \"Posteringer ToList\" started","metadata":{"file":"WorkflowExecute.js","node":"Posteringer ToList","timestamp":"2022-10-25T10:37:32.194Z","workflowId":1}}
{"level":"debug","message":"Running node \"Posteringer ToList\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Posteringer ToList","timestamp":"2022-10-25T10:37:32.218Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"Set1\"","metadata":{"file":"WorkflowExecute.js","node":"Set1","timestamp":"2022-10-25T10:37:32.234Z","workflowId":1}}
{"level":"debug","message":"Running node \"Set1\" started","metadata":{"file":"WorkflowExecute.js","node":"Set1","timestamp":"2022-10-25T10:37:32.234Z","workflowId":1}}
{"level":"debug","message":"Running node \"Set1\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Set1","timestamp":"2022-10-25T10:37:44.780Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"TRUNCATE TABLES\"","metadata":{"file":"WorkflowExecute.js","node":"TRUNCATE TABLES","timestamp":"2022-10-25T10:37:44.796Z","workflowId":1}}
{"level":"debug","message":"Running node \"TRUNCATE TABLES\" started","metadata":{"file":"WorkflowExecute.js","node":"TRUNCATE TABLES","timestamp":"2022-10-25T10:37:44.796Z","workflowId":1}}
{"level":"debug","message":"Running node \"TRUNCATE TABLES\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"TRUNCATE TABLES","timestamp":"2022-10-25T10:37:45.539Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"Microsoft SQL\"","metadata":{"file":"WorkflowExecute.js","node":"Microsoft SQL","timestamp":"2022-10-25T10:37:45.540Z","workflowId":1}}
{"level":"debug","message":"Running node \"Microsoft SQL\" started","metadata":{"file":"WorkflowExecute.js","node":"Microsoft SQL","timestamp":"2022-10-25T10:37:45.540Z","workflowId":1}}
{"level":"debug","message":"Running node \"Microsoft SQL\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Microsoft SQL","timestamp":"2022-10-25T10:37:55.516Z","workflowId":1}}
{"level":"debug","message":"Start processing node \"Execute monday.jsondata\"","metadata":{"file":"WorkflowExecute.js","node":"Execute monday.jsondata","timestamp":"2022-10-25T10:37:55.532Z","workflowId":1}}
{"level":"debug","message":"Running node \"Execute monday.jsondata\" started","metadata":{"file":"WorkflowExecute.js","node":"Execute monday.jsondata","timestamp":"2022-10-25T10:37:55.532Z","workflowId":1}}
{"level":"verbose","message":"Workflow execution started","metadata":{"file":"WorkflowExecute.js","function":"processRunExecutionData","timestamp":"2022-10-25T10:37:55.790Z","workflowId":"2"}}
{"level":"debug","message":"Start processing node \"Start\"","metadata":{"file":"WorkflowExecute.js","node":"Start","timestamp":"2022-10-25T10:37:55.794Z","workflowId":"2"}}
{"level":"debug","message":"Running node \"Start\" started","metadata":{"file":"WorkflowExecute.js","node":"Start","timestamp":"2022-10-25T10:37:55.794Z","workflowId":"2"}}
{"level":"debug","message":"Running node \"Start\" finished successfully","metadata":{"file":"WorkflowExecute.js","node":"Start","timestamp":"2022-10-25T10:37:55.795Z","workflowId":"2"}}
{"level":"debug","message":"Start processing node \"GET count.boardIDs\"","metadata":{"file":"WorkflowExecute.js","node":"GET count.boardIDs","timestamp":"2022-10-25T10:37:55.810Z","workflowId":"2"}}
{"level":"debug","message":"Running node \"GET count.boardIDs\" started","metadata":{"file":"WorkflowExecute.js","node":"GET count.boardIDs","timestamp":"2022-10-25T10:37:55.810Z","workflowId":"2"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:55.814Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:56.172Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:56.454Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:56.696Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:57.293Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:58.018Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:58.263Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:58.512Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:58.824Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T10:37:59.059Z"}}


(THIS GOES ON FOR A LONG TIME, CHECK TIMESTAMPS)


{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T12:07:58.087Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T12:07:58.333Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T12:07:58.351Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T12:07:58.597Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T12:07:58.684Z"}}
{"level":"debug","message":"Proxying request to axios","metadata":{"file":"NodeExecuteFunctions.js","function":"proxyRequestToAxios","timestamp":"2022-10-25T12:07:58.857Z"}}

Also, I tried switching database to postgres and set up my workflows again, same issue.

Jon · October 25, 2022, 2:21pm

So this is where our text logging isn’t great, In the UI what is happening is it still spinning like it is doing something? A screenshot of what that looks like would be great, I am starting to think it is just taking a while to go through all the items.

What is the node called GET count.boardIDs doing?

samsamthewise · October 26, 2022, 5:35am

I just let it run overnight to see what would happen

Since it’s a triggered run, UI of the actual workflow doesn’t show anything (unless there’s a UI feature I’m missing?)

But here’s how the execution log looks.

Also, as you can see on the picture, when I hit the stop button of triggered workflow, it’s just keeps spinning.

If I hit stop button on integrated workflow, it returns this error:

Hoping that by chance these things indicate something to you of where I should look.

The “GET count.boardIDs” node is making this graphql request.

query {
boards(limit: 20){
id
}
}

Mind you, I usually have this set to “limit: 5000”, to retrieve all board IDs from this service, which has worked fine in the past. I have it at “limit: 20” for testing.

I’ve had the same thought “is it just going through all items, but incredibly slowly?”, but even if it was, it’s obviously not sustainable. And after tonights test, I think there’s something awfully wrong.

List of some the things i’ve tried:

simplify workflows to try and reach a successful test-run
Downgrade n8n version to version used when the whole thing worked (v. 0.190.0)
Re-install n8n container
enable logging, execution data saving & pruning
manually pruning sqlite database
Switch database to postgresdb
-e EXECUTIONS_PROCESS = main

This list is probably not exhaustive, but what I can recall off the top of my head.

Jon · October 27, 2022, 2:32pm

That is strange but it just looks like it is running so I can’t see any actual issue there other than it is taking a long time which seems very strange.

You mentioned it worked on 190.0 so does that mean downgrading to 190.0 got it up and running again or did the issue continue happen? That feels important as if it doesn’t work but did work it could be something outside of n8n having an impact on it.

Have you tried putting everything into one workflow to see if that changes anything?

samsamthewise · November 1, 2022, 5:41am

No downgrading didn’t work. Sure, it could be something outside of n8n, but I’m not sure how to debug it.

I’ll try consolidating some of the workflows and see if that runs, which I expect it will. Well, that might run me into memory issues though.

Jon · November 1, 2022, 11:21am

Hey @samsamthewise,

I guess the good news is we know it isn’t related to the version of n8n, I can see what could be workflow 1 in the original post but I don’t think we ever got workflow 2. Is that something you could share?