Tuesday, March 27, 2018

Daphne server on Heroku will not allow app to start

The dockerized app successfully uploads and the code is hosted on Heroku's docker container registry. I can shell inside, run `python manage.py migrate` or `./manage.py runserver`, etc.  

So the chokepoint likely seems to be the daphne command I'm running.  

`web: daphne config.asgi:application --port $PORT --bind 0.0.0.0 -v2`  

That^ is what I'm running, and here's the output:  

web.1]: Starting process with command `/bin/sh -c daphne\ -b\ 0.0.0.0:\29689\ config.asgi:application\ -v2`
2018-03-27T12:44:33.812281+00:00 app[web.1]: 2018-03-27 12:44:33,811 DEBUG    Importing BmpImagePlugin
2018-03-27T12:44:33.821615+00:00 app[web.1]: 2018-03-27 12:44:33,821 DEBUG    Importing BufrStubImagePlugin
2018-03-27T12:44:33.823011+00:00 app[web.1]: 2018-03-27 12:44:33,822 DEBUG    Importing CurImagePlugin
2018-03-27T12:44:33.824396+00:00 app[web.1]: 2018-03-27 12:44:33,824 DEBUG    Importing DcxImagePlugin
2018-03-27T12:44:33.826218+00:00 app[web.1]: 2018-03-27 12:44:33,826 DEBUG    Importing DdsImagePlugin
2018-03-27T12:44:33.827627+00:00 app[web.1]: 2018-03-27 12:44:33,827 DEBUG    Importing EpsImagePlugin
2018-03-27T12:44:33.830181+00:00 app[web.1]: 2018-03-27 12:44:33,829 DEBUG    Importing FitsStubImagePlugin
2018-03-27T12:44:33.830948+00:00 app[web.1]: 2018-03-27 12:44:33,830 DEBUG    Importing FliImagePlugin
2018-03-27T12:44:33.831989+00:00 app[web.1]: 2018-03-27 12:44:33,831 DEBUG    Importing FpxImagePlugin
2018-03-27T12:44:33.833280+00:00 app[web.1]: 2018-03-27 12:44:33,833 DEBUG    Image: failed to import FpxImagePlugin: No module named 'olefile'
2018-03-27T12:44:33.833637+00:00 app[web.1]: 2018-03-27 12:44:33,833 DEBUG    Importing FtexImagePlugin
2018-03-27T12:44:33.834481+00:00 app[web.1]: 2018-03-27 12:44:33,834 DEBUG    Importing GbrImagePlugin
2018-03-27T12:44:33.835321+00:00 app[web.1]: 2018-03-27 12:44:33,835 DEBUG    Importing GifImagePlugin
2018-03-27T12:44:33.837724+00:00 app[web.1]: 2018-03-27 12:44:33,837 DEBUG    Importing GribStubImagePlugin
2018-03-27T12:44:33.838402+00:00 app[web.1]: 2018-03-27 12:44:33,838 DEBUG    Importing Hdf5StubImagePlugin
2018-03-27T12:44:33.860234+00:00 app[web.1]: 2018-03-27 12:44:33,859 DEBUG    Importing IcnsImagePlugin
2018-03-27T12:44:33.864684+00:00 app[web.1]: 2018-03-27 12:44:33,864 DEBUG    Importing IcoImagePlugin
2018-03-27T12:44:33.865808+00:00 app[web.1]: 2018-03-27 12:44:33,865 DEBUG    Importing ImImagePlugin
2018-03-27T12:44:33.873231+00:00 app[web.1]: 2018-03-27 12:44:33,868 DEBUG    Importing ImtImagePlugin
2018-03-27T12:44:33.877829+00:00 app[web.1]: 2018-03-27 12:44:33,877 DEBUG    Importing IptcImagePlugin
2018-03-27T12:44:33.882787+00:00 app[web.1]: 2018-03-27 12:44:33,882 DEBUG    Importing JpegImagePlugin
2018-03-27T12:44:33.898882+00:00 app[web.1]: 2018-03-27 12:44:33,898 DEBUG    Importing Jpeg2KImagePlugin
2018-03-27T12:44:33.899310+00:00 app[web.1]: 2018-03-27 12:44:33,898 DEBUG    Importing McIdasImagePlugin
2018-03-27T12:44:33.900368+00:00 app[web.1]: 2018-03-27 12:44:33,900 DEBUG    Importing MicImagePlugin
2018-03-27T12:44:33.901740+00:00 app[web.1]: 2018-03-27 12:44:33,901 DEBUG    Image: failed to import MicImagePlugin: No module named 'olefile'
2018-03-27T12:44:33.901959+00:00 app[web.1]: 2018-03-27 12:44:33,901 DEBUG    Importing MpegImagePlugin
2018-03-27T12:44:33.902994+00:00 app[web.1]: 2018-03-27 12:44:33,902 DEBUG    Importing MpoImagePlugin
2018-03-27T12:44:33.903992+00:00 app[web.1]: 2018-03-27 12:44:33,903 DEBUG    Importing MspImagePlugin
2018-03-27T12:44:33.904966+00:00 app[web.1]: 2018-03-27 12:44:33,904 DEBUG    Importing PalmImagePlugin
2018-03-27T12:44:33.907002+00:00 app[web.1]: 2018-03-27 12:44:33,906 DEBUG    Importing PcdImagePlugin
2018-03-27T12:44:33.907930+00:00 app[web.1]: 2018-03-27 12:44:33,907 DEBUG    Importing PcxImagePlugin
2018-03-27T12:44:33.908142+00:00 app[web.1]: 2018-03-27 12:44:33,908 DEBUG    Importing PdfImagePlugin
2018-03-27T12:44:33.909400+00:00 app[web.1]: 2018-03-27 12:44:33,909 DEBUG    Importing PixarImagePlugin
2018-03-27T12:44:33.910391+00:00 app[web.1]: 2018-03-27 12:44:33,910 DEBUG    Importing PngImagePlugin
2018-03-27T12:44:33.910620+00:00 app[web.1]: 2018-03-27 12:44:33,910 DEBUG    Importing PpmImagePlugin
2018-03-27T12:44:33.911668+00:00 app[web.1]: 2018-03-27 12:44:33,911 DEBUG    Importing PsdImagePlugin
2018-03-27T12:44:33.912740+00:00 app[web.1]: 2018-03-27 12:44:33,912 DEBUG    Importing SgiImagePlugin
2018-03-27T12:44:33.914048+00:00 app[web.1]: 2018-03-27 12:44:33,913 DEBUG    Importing SpiderImagePlugin
2018-03-27T12:44:33.915493+00:00 app[web.1]: 2018-03-27 12:44:33,915 DEBUG    Importing SunImagePlugin
2018-03-27T12:44:33.916508+00:00 app[web.1]: 2018-03-27 12:44:33,916 DEBUG    Importing TgaImagePlugin
2018-03-27T12:44:33.917574+00:00 app[web.1]: 2018-03-27 12:44:33,917 DEBUG    Importing TiffImagePlugin
2018-03-27T12:44:33.917765+00:00 app[web.1]: 2018-03-27 12:44:33,917 DEBUG    Importing WebPImagePlugin
2018-03-27T12:44:33.919789+00:00 app[web.1]: 2018-03-27 12:44:33,919 DEBUG    Importing WmfImagePlugin
2018-03-27T12:44:33.920524+00:00 app[web.1]: 2018-03-27 12:44:33,920 DEBUG    Importing XbmImagePlugin
2018-03-27T12:44:33.923447+00:00 app[web.1]: 2018-03-27 12:44:33,923 DEBUG    Importing XpmImagePlugin
2018-03-27T12:44:33.924692+00:00 app[web.1]: 2018-03-27 12:44:33,924 DEBUG    Importing XVThumbImagePlugin
2018-03-27T12:44:34.272091+00:00 app[web.1]: 2018-03-27 12:44:34,271 DEBUG    get 'DJANGO_READ_DOT_ENV_FILE' casted as '<class 'bool'>' with default 'False'
2018-03-27T12:44:34.289223+00:00 app[web.1]: 2018-03-27 12:44:34,285 DEBUG    get 'DJANGO_DEBUG' casted as '<class 'bool'>' with default 'False'
2018-03-27T12:44:34.291317+00:00 app[web.1]: 2018-03-27 12:44:34,290 DEBUG    get 'DATABASE_URL' casted as 'None' with default '<NoValue>'
2018-03-27T12:44:34.308760+00:00 app[web.1]: 2018-03-27 12:44:34,302 DEBUG    get 'DJANGO_EMAIL_BACKEND' casted as 'None' with default 'django.core.mail.backends.smtp.EmailBackend'
2018-03-27T12:44:34.308773+00:00 app[web.1]: 2018-03-27 12:44:34,303 DEBUG    get 'DJANGO_ACCOUNT_ALLOW_REGISTRATION' casted as '<class 'bool'>' with default 'True'
2018-03-27T12:44:34.308780+00:00 app[web.1]: 2018-03-27 12:44:34,303 DEBUG    get 'DJANGO_SECRET_KEY' casted as 'None' with default '<NoValue>'
2018-03-27T12:44:34.308782+00:00 app[web.1]: 2018-03-27 12:44:34,303 DEBUG    get 'DJANGO_ALLOWED_HOSTS' casted as '<class 'list'>' with default '['example.com']'
2018-03-27T12:44:34.308789+00:00 app[web.1]: 2018-03-27 12:44:34,303 DEBUG    get 'DATABASE_URL' casted as 'None' with default '<NoValue>'
2018-03-27T12:44:34.308791+00:00 app[web.1]: 2018-03-27 12:44:34,304 DEBUG    get 'CONN_MAX_AGE' casted as '<class 'int'>' with default '60'
2018-03-27T12:44:34.308792+00:00 app[web.1]: 2018-03-27 12:44:34,305 DEBUG    get 'REDIS_URL' casted as 'None' with default 'redis://127.0.0.1:6379'
2018-03-27T12:44:34.308794+00:00 app[web.1]: 2018-03-27 12:44:34,305 DEBUG    get 'DJANGO_SECURE_SSL_REDIRECT' casted as '<class 'bool'>' with default 'True'
2018-03-27T12:44:34.308796+00:00 app[web.1]: 2018-03-27 12:44:34,305 DEBUG    get 'DJANGO_SECURE_HSTS_INCLUDE_SUBDOMAINS' casted as '<class 'bool'>' with default 'True'
2018-03-27T12:44:34.308797+00:00 app[web.1]: 2018-03-27 12:44:34,305 DEBUG    get 'DJANGO_SECURE_HSTS_PRELOAD' casted as '<class 'bool'>' with default 'True'
2018-03-27T12:44:34.308799+00:00 app[web.1]: 2018-03-27 12:44:34,306 DEBUG    get 'DJANGO_SECURE_CONTENT_TYPE_NOSNIFF' casted as '<class 'bool'>' with default 'True'
2018-03-27T12:44:34.308800+00:00 app[web.1]: 2018-03-27 12:44:34,306 DEBUG    get 'DJANGO_AWS_ACCESS_KEY_ID' casted as 'None' with default '<NoValue>'
2018-03-27T12:44:34.308802+00:00 app[web.1]: 2018-03-27 12:44:34,306 DEBUG    get 'DJANGO_AWS_SECRET_ACCESS_KEY' casted as 'None' with default '<NoValue>'
2018-03-27T12:44:34.308803+00:00 app[web.1]: 2018-03-27 12:44:34,306 DEBUG    get 'DJANGO_AWS_STORAGE_BUCKET_NAME' casted as 'None' with default '<NoValue>'
2018-03-27T12:44:34.308805+00:00 app[web.1]: 2018-03-27 12:44:34,306 DEBUG    get 'DJANGO_DEFAULT_FROM_EMAIL' casted as 'None' with default 'pair <noreply@example.com>'
2018-03-27T12:44:34.308807+00:00 app[web.1]: 2018-03-27 12:44:34,306 DEBUG    get 'DJANGO_SERVER_EMAIL' casted as 'None' with default 'pair <noreply@example.com>'
2018-03-27T12:44:34.308808+00:00 app[web.1]: 2018-03-27 12:44:34,307 DEBUG    get 'DJANGO_EMAIL_SUBJECT_PREFIX' casted as 'None' with default '[pair]'
2018-03-27T12:44:34.317419+00:00 app[web.1]: 2018-03-27 12:44:34,315 DEBUG    get 'DJANGO_ADMIN_URL' casted as 'None' with default '<NoValue>'
2018-03-27T12:44:34.317422+00:00 app[web.1]: 2018-03-27 12:44:34,316 DEBUG    get 'MAILGUN_API_KEY' casted as 'None' with default '<NoValue>'
2018-03-27T12:44:34.317424+00:00 app[web.1]: 2018-03-27 12:44:34,316 DEBUG    get 'MAILGUN_DOMAIN' casted as 'None' with default '<NoValue>'
2018-03-27T12:44:36.653553+00:00 heroku[web.1]: State changed from starting to crashed
2018-03-27T12:44:36.655274+00:00 heroku[web.1]: State changed from crashed to starting
2018-03-27T12:44:36.635592+00:00 heroku[web.1]: Process exited with status 0
2018-03-27T12:44:20+00:00 app[heroku-redis]: source=REDIS sample#active-connections=1 sample#load-avg-1m=0.025 sample#load-avg-5m=0.01 sample#load-avg-15m=0 sample#read-iops=0 sample#write-iops=0 sample#memory-total=15664376kB sample#memory-free=14945112kB sample#memory-cached=371076kB sample#memory-redis=278200bytes sample#hit-rate=1 sample#evicted-keys=0
2018-03-27T12:45:01.113889+00:00 heroku[web.1]: Starting process with command `/bin/sh -c daphne\ -b\ 0.0.0.0:\13804\ config.asgi:application\ -v2`
2018-03-27T12:45:04.495376+00:00 app[web.1]: 2018-03-27 12:45:04,494 DEBUG    Importing BmpImagePlugin
2018-03-27T12:45:04.501403+00:00 app[web.1]: 2018-03-27 12:45:04,501 DEBUG    Importing BufrStubImagePlugin


It just spits out all the ENV vars. The Heroku app will not open.  

If I run it locally using a Procfile and then go to localhost, I will see Daphne's 500 Internal Server Error page.  

Any idea?  

I came across this https://github.com/django/channels/issues/872 issue in which Andrew briefly mentions that a Raven install may cause issues.  
Though my app is using Raven, I've commented out all of its related settings for now.  
He also mentions that it isn't an issue related to not running `runworker`.   

aioredis==1.0.0

asgiref==2.2.0

channels==2.0.2

channels-redis==2.1.1

daphne==2.1.0

dj-database-url==0.5.0

Django==1.11.8

django-redis==4.9.0

gunicorn==19.7.1

raven==6.6.0

redis==2.10.6

Twisted==17.9.0






--
You received this message because you are subscribed to the Google Groups "Django users" group.
To unsubscribe from this group and stop receiving emails from it, send an email to django-users+unsubscribe@googlegroups.com.
To post to this group, send email to django-users@googlegroups.com.
Visit this group at https://groups.google.com/group/django-users.
To view this discussion on the web visit https://groups.google.com/d/msgid/django-users/96dc7a70-7a2c-4c42-9b82-37d39b5a918e%40googlegroups.com.
For more options, visit https://groups.google.com/d/optout.

No comments:

Post a Comment