uvicorn-gunicorn-fastapi-docker icon indicating copy to clipboard operation
uvicorn-gunicorn-fastapi-docker copied to clipboard

Workers go into restarting/crash cycle (WORKER TIMEOUT / signal 6)

Open lsmith77 opened this issue 3 years ago • 26 comments

I am struggling to know which layer is the root cause here.

My app runs fine, but then suddenly it is unable to serve requests for a while and then "fixes itself". While it's unable to serve requests my logs show:

[2022-01-18 08:36:46 +0000] [1505] [CRITICAL] WORKER TIMEOUT (pid:1548)
[2022-01-18 08:36:46 +0000] [1505] [CRITICAL] WORKER TIMEOUT (pid:1575)
[2022-01-18 08:36:46 +0000] [1505] [WARNING] Worker with pid 1548 was terminated due to signal 6
[2022-01-18 08:36:46 +0000] [1505] [WARNING] Worker with pid 1575 was terminated due to signal 6
[2022-01-18 08:36:46 +0000] [1783] [INFO] Booting worker with pid: 1783
[2022-01-18 08:36:46 +0000] [1782] [INFO] Booting worker with pid: 1782
[2022-01-18 08:36:47 +0000] [1505] [CRITICAL] WORKER TIMEOUT (pid:1577)
[2022-01-18 08:36:47 +0000] [1505] [CRITICAL] WORKER TIMEOUT (pid:1578)
[2022-01-18 08:36:47 +0000] [1505] [WARNING] Worker with pid 1578 was terminated due to signal 6
[2022-01-18 08:36:47 +0000] [1784] [INFO] Booting worker with pid: 1784
[2022-01-18 08:36:47 +0000] [1505] [WARNING] Worker with pid 1577 was terminated due to signal 6
[2022-01-18 08:36:47 +0000] [1785] [INFO] Booting worker with pid: 1785
[2022-01-18 08:36:51 +0000] [1505] [CRITICAL] WORKER TIMEOUT (pid:1545)
[2022-01-18 08:36:51 +0000] [1505] [CRITICAL] WORKER TIMEOUT (pid:1551)
[2022-01-18 08:36:51 +0000] [1505] [CRITICAL] WORKER TIMEOUT (pid:1559)
[2022-01-18 08:36:52 +0000] [1505] [WARNING] Worker with pid 1551 was terminated due to signal 6

Initially, I thought it was related to load and resource limits, but it seems to also happen during "typical load" and when resources are nowhere near their limits.

lsmith77 avatar Jan 27 '22 15:01 lsmith77

BTW I saw this ticket here https://github.com/tiangolo/uvicorn-gunicorn-fastapi-docker/issues/47 but I think its not the same issue.

lsmith77 avatar Jan 27 '22 15:01 lsmith77

Facing the same issue. Whenever following code is executed with incorrect smtp_url, port, my worker crashes:

def validate_smtp(smtp_url: str):
     try:
         smtp = SMTP()
         smtp.connect(smtp_url)
         smtp.quit()
         return True
     except:
         return False

There is no crash if smtp_url or port is valid. Dependencies:

  1. gunicorn: 20.1.0
  2. uvicorn: 0.17.1
  3. fastapi: 0.73.0

udit-pandey avatar Feb 01 '22 06:02 udit-pandey

I'm also facing the same issue. any workarounds?

AnjaneyuluBatta505 avatar Mar 29 '22 05:03 AnjaneyuluBatta505

i resolved this issue by adding worker timeout while initiating my gunicorn application.

gunicorn -k uvicorn.workers.UvicornWorker ${APP_MODULE} --bind 0.0.0.0:80 --timeout ${WORKER_TIMEOUT}

udit-pandey avatar Mar 29 '22 06:03 udit-pandey

Facing the same issue when running long processes on websockets and it ends up terminating the websocket connection. Any fixes?

mudassirzr avatar Apr 21 '22 08:04 mudassirzr

Facing the same issue when I use the haystack. I modifed the docker-compose.yml as following: command: "/bin/bash -c 'sleep 10 && gunicorn rest_api.application:app -b 0.0.0.0 -k uvicorn.workers.UvicornWorker -- workers 1 --timeout 600'" It can work.

yuanwu2017 avatar May 31 '22 04:05 yuanwu2017

Facing this issue while using docker. Working perfectly fine if run directly with gunicorn -w 1 -k uvicorn.workers.UvicornWorker --bind 0.0.0.0:8080 main:app.

None of the following suggested solutions worked:

  1. Assigning more memory
  2. Changing worker class to gevent
  3. Changing python version to 3.7 from 3.9
  4. Adding timeout
  5. Running directly with uvicorn without gunicorn

Can someone please point me in the right direction to resolve this issue?

ankitksharma avatar Jul 12 '22 10:07 ankitksharma

Facing this same issue (on both CentOS and Ubuntu VM's), it happens during typical load and all resources are not near limits.

komljenovicnikola avatar Jul 24 '22 19:07 komljenovicnikola

Same here, anyone can suggest a good alternative?

merryHunter avatar Jul 29 '22 05:07 merryHunter

In my case it seemed to happen due to request timeout to external service.

merryHunter avatar Jul 29 '22 06:07 merryHunter

Any solution for this ? Facing the same issue when calling an endpoint which takes 1-2 min to execute.

atTheShikhar avatar Aug 09 '22 07:08 atTheShikhar

@atTheShikhar for me - I switched to combination Flask + uWSGI.

merryHunter avatar Aug 09 '22 07:08 merryHunter

@atTheShikhar for me - I switched to combination Flask + uWSGI.

Unfortunately i cannot change the SGI and framework, since most of the work are already done in my case. Just needed this one endpoint to work.

atTheShikhar avatar Aug 09 '22 07:08 atTheShikhar

Can you show your launching command? what parameters do you use? I assume gunicorn should run smoothly when using 1 worker on a single process.

merryHunter avatar Aug 09 '22 07:08 merryHunter

Can you show your launching command? what parameters do you use? I assume gunicorn should run smoothly when using 1 worker on a single process.

I am using docker so the final running command is this (the timeout part was added after reading above discussion) CMD ["gunicorn", "-w", "4", "-k", "uvicorn.workers.UvicornWorker", "main:app", "--bind", "0.0.0.0:80", "--timeout", "300"]

Btw, this runs just fine locally. Problem only happens after i deploy it on GCR.

atTheShikhar avatar Aug 09 '22 07:08 atTheShikhar

hmm, for me it struggles both on EC2 machine and on Fargate... but so did you try running 1 worker? just to make work,

merryHunter avatar Aug 09 '22 07:08 merryHunter

yup i tried with 1 worker, still no luck.

atTheShikhar avatar Aug 09 '22 07:08 atTheShikhar

anyone have any update?

Aditya23456 avatar Sep 03 '22 00:09 Aditya23456

I've had the same issue. BUT, it only comes up when I started using max_requests setting...

rbannon-tc avatar Sep 23 '22 19:09 rbannon-tc

Adding some info if it helps. Gunicorn config below is run via supervisor, and was fine for a while. Added FastAPI Cache, all was good as well but crash rate has increased dramatically in past few days.

bind = "0.0.0.0:<PORT>"
wsgi_app = "main:app"
workers = 3 # worked for a while using 1 worker
worker_class = "uvicorn.workers.UvicornWorker"
errorlog = '<LOG_FILE>'
accesslog = '<LOG_FILE>'
loglevel = 'debug'
timeout = 240  # been increasing from 30s to solve [CRITICAL] WORKER TIMEOUT, now at 240s and still crashes occassionally

Server RAM: 1.9GB

Thanks

inspaya avatar Nov 20 '22 11:11 inspaya

Managed to resolve this issue, sharing in case this helps.

Our issue originated from making external API calls from within an async endpoint. These API calls did not support async, which introduced blocking calls to the event loop, resulting in the uvicorn worker timing out. Our reliance on FastAPI Cache decorators for these async endpoints prevented us from simply redefining these endpoints as sync (async def -> def).

To resolve, we made use of the run_in_threadpool() utility function to ensure these sync calls are run in a separate threadpool, outside the event loop. Alongside this, we updated our gunicorn config so the workers and threads count was equal - setting these to 4.

from fastapi.concurrency import run_in_threadpool

@api.get('/handler')
async def handler():
    ...
    # Slow async function
    await my_async_function()
    ....
    # Slow running sync function
    await run_in_threadpool(sync_function)

We released this update over 2 weeks ago and haven't seen any worker timeouts. Hopefully this helps 🙂

nicholasmccrea avatar Feb 23 '23 15:02 nicholasmccrea

@nicholasmccrea wow, should have been hard to identify it! Great news!

merryHunter avatar Feb 23 '23 15:02 merryHunter

@nicholarmccrea your solution seems like a good solution, but i imagined removing the "async" from the endpoint functions allowed the requests to be handled in a different threadpool. Do you need to explicitly run it in a seperate function?

I am taking the knowledge from the comment on this post : https://stackoverflow.com/questions/71516140/fastapi-runs-api-calls-in-serial-instead-of-parallel-fashion

mcazim98 avatar Apr 11 '23 06:04 mcazim98

@nicholarmccrea your solution seems like a good solution, but i imagined removing the "async" from the endpoint functions allowed the requests to be handled in a different threadpool. Do you need to explicitly run it in a seperate function?

I am taking the knowledge from the comment on this post : https://stackoverflow.com/questions/71516140/fastapi-runs-api-calls-in-serial-instead-of-parallel-fashion

@mcazim98 in our case we were not able to redefine our endpoints as sync due to our reliance on FastAPI cache decorators. The FastAPI cache version we were using was 0.1.8, which did not support sync functions, therefore we needed to use the run_in_threadpool utility function as a workaround.

Thankfully, FastAPI cache now support sync functions as of version 0.2.0, which means we can now redefine our endpoints as sync and move away from using the run_in_threadpool function 🙂

nicholasmccrea avatar Apr 12 '23 14:04 nicholasmccrea

Yes I was having similar issues where endpoint was requesting a response from an ML model running within the saem fastapi application. Redefining the endpoints to sync versions fixed the issue. Since this was a CPU bound task I do not think async was really necessary here, but I still do not understand why it was causing the workers to constantly crash although I experimented with different timeout settings.

ruslaniv avatar Apr 25 '23 05:04 ruslaniv

I encountered a similar issue, running FastAPI backend, where endpoint seemed to randomly ignore my requests sometimes, but sometimes 'fixes itself' and works. After spending 2 weeks on this, I realized this had to do with ports. My fix was to:

  1. Explicitly forward the ports in my Docker container by including -p 8000:8000 in the docker run command. docker run -dit --gpus all -p 8000:8000 my_image
  2. Then add --host 0.0.0.0 to my uvicorn command. uvicorn src:app --host 0.0.0.0

kenJPG avatar May 01 '23 16:05 kenJPG