Can't get Bookwyrm running

[Neriderc]

I'm trying to get Bookwrym running for the first time, and have followed these instructions https://docs.joinbookwyrm.com/install-prod.html
I have also followed https://docs.joinbookwyrm.com/reverse-proxy.html since I am running other services as well.

After starting the container it just... doesn't work. I can't access it through the browser, and I am not entirely sure it's running properly.

The first time I ran it I got a bunch of warnings, but when I did docker-compose down && docker-compose up they seem to have gone away. However, I still can't access it, either through my reverse proxy, or directly at port 8001.

I'm running on a Raspberry Pi 4 and accessing via a different machine on the same network.

When I try to access it via the reverse proxy it times out, if I try via local IP + port then it just says it can't connect.

When running the container I get the following log:

docker-compose logs -f
Attaching to bookwyrm_nginx_1, bookwyrm_celery_beat_1, bookwyrm_web_1, bookwyrm_flower_1, bookwyrm_celery_worker_1, bookwyrm_db_1, bookwyrm_redis_activity_1, bookwyrm_dev-tools_1, bookwyrm_redis_broker_1
celery_beat_1     | celery beat v5.2.2 (dawn-chorus) is starting.
celery_beat_1     | __    -    ... __   -        _
celery_beat_1     | LocalTime -> 2022-05-18 23:47:51
celery_beat_1     | Configuration ->
celery_beat_1     |     . broker -> redis://:**@redis_broker:6379/0
celery_beat_1     |     . loader -> celery.loaders.app.AppLoader
celery_beat_1     |     . scheduler -> django_celery_beat.schedulers.DatabaseScheduler
celery_beat_1     | 
celery_beat_1     |     . logfile -> [stderr]@%INFO
celery_beat_1     |     . maxinterval -> 5.00 seconds (5s)
celery_beat_1     | [2022-05-18 23:47:51,220: INFO/MainProcess] beat: Starting...
db_1              | Starting periodic command scheduler: cron.
db_1              | 
db_1              | PostgreSQL Database directory appears to contain a database; Skipping initialization
db_1              | 
db_1              | 2022-05-18 23:47:36.973 UTC [1] LOG:  starting PostgreSQL 13.0 (Debian 13.0-1.pgdg100+1) on aarch64-unknown-linux-gnu, compiled by gcc (Debian 8.3.0-6) 8.3.0, 64-bit
db_1              | 2022-05-18 23:47:36.974 UTC [1] LOG:  listening on IPv4 address "0.0.0.0", port 5432
db_1              | 2022-05-18 23:47:36.974 UTC [1] LOG:  listening on IPv6 address "::", port 5432
db_1              | 2022-05-18 23:47:36.980 UTC [1] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
db_1              | 2022-05-18 23:47:37.024 UTC [49] LOG:  database system was shut down at 2022-05-18 23:45:41 UTC
db_1              | 2022-05-18 23:47:37.071 UTC [1] LOG:  database system is ready to accept connections
celery_worker_1   | /usr/local/lib/python3.9/site-packages/celery/platforms.py:840: SecurityWarning: You're running the worker with superuser privileges: this is
celery_worker_1   | absolutely not recommended!
celery_worker_1   | 
celery_worker_1   | Please specify a different user using the --uid option.
celery_worker_1   | 
celery_worker_1   | User information: uid=0 euid=0 gid=0 egid=0
celery_worker_1   | 
celery_worker_1   |   warnings.warn(SecurityWarning(ROOT_DISCOURAGED.format(
celery_worker_1   |  
celery_worker_1   |  -------------- celery@c65466b685e0 v5.2.2 (dawn-chorus)
celery_worker_1   | --- ***** ----- 
celery_worker_1   | -- ******* ---- Linux-5.15.0-1006-raspi-aarch64-with-glibc2.31 2022-05-18 23:47:49
celery_worker_1   | - *** --- * --- 
celery_worker_1   | - ** ---------- [config]
celery_worker_1   | - ** ---------- .> app:         celerywyrm:0xffffa3581310
celery_worker_1   | - ** ---------- .> transport:   redis://:**@redis_broker:6379/0
celery_worker_1   | - ** ---------- .> results:     redis://:**@redis_broker:6379/0
celery_worker_1   | - *** --- * --- .> concurrency: 4 (prefork)
celery_worker_1   | -- ******* ---- .> task events: OFF (enable -E to monitor tasks in this worker)
celery_worker_1   | --- ***** ----- 
celery_worker_1   |  -------------- [queues]
celery_worker_1   |                 .> high_priority    exchange=high_priority(direct) key=high_priority
celery_worker_1   |                 .> low_priority     exchange=low_priority(direct) key=low_priority
celery_worker_1   |                 .> medium_priority  exchange=medium_priority(direct) key=medium_priority
celery_worker_1   | 
celery_worker_1   | [tasks]
celery_worker_1   |   . bookwyrm.activitypub.base_activity.set_related_field
celery_worker_1   |   . bookwyrm.activitystreams.add_book_statuses_task
celery_worker_1   |   . bookwyrm.activitystreams.add_status_task
celery_worker_1   |   . bookwyrm.activitystreams.add_user_statuses_task
celery_worker_1   |   . bookwyrm.activitystreams.handle_boost_task
celery_worker_1   |   . bookwyrm.activitystreams.populate_stream_task
celery_worker_1   |   . bookwyrm.activitystreams.remove_book_statuses_task
celery_worker_1   |   . bookwyrm.activitystreams.remove_status_task
celery_worker_1   |   . bookwyrm.activitystreams.remove_user_statuses_task
celery_worker_1   |   . bookwyrm.connectors.connector_manager.load_more_data
celery_worker_1   |   . bookwyrm.emailing.send_email
celery_worker_1   |   . bookwyrm.importers.importer.import_item_task
celery_worker_1   |   . bookwyrm.importers.importer.start_import_task
celery_worker_1   |   . bookwyrm.lists_stream.add_list_task
celery_worker_1   |   . bookwyrm.lists_stream.add_user_lists_task
celery_worker_1   |   . bookwyrm.lists_stream.populate_lists_task
celery_worker_1   |   . bookwyrm.lists_stream.remove_list_task
celery_worker_1   |   . bookwyrm.lists_stream.remove_user_lists_task
celery_worker_1   |   . bookwyrm.models.activitypub_mixin.broadcast_task
celery_worker_1   |   . bookwyrm.models.antispam.automod_task
celery_worker_1   |   . bookwyrm.models.user.get_remote_reviews
celery_worker_1   |   . bookwyrm.models.user.set_remote_server
celery_worker_1   |   . bookwyrm.preview_images.generate_edition_preview_image_task
celery_worker_1   |   . bookwyrm.preview_images.generate_site_preview_image_task
celery_worker_1   |   . bookwyrm.preview_images.generate_user_preview_image_task
celery_worker_1   |   . bookwyrm.suggested_users.bulk_add_instance_task
celery_worker_1   |   . bookwyrm.suggested_users.bulk_remove_instance_task
celery_worker_1   |   . bookwyrm.suggested_users.remove_suggestion_task
celery_worker_1   |   . bookwyrm.suggested_users.remove_user_task
celery_worker_1   |   . bookwyrm.suggested_users.rerank_suggestions_task
celery_worker_1   |   . bookwyrm.suggested_users.rerank_user_task
celery_worker_1   |   . bookwyrm.views.inbox.activity_task
celery_worker_1   |   . imagekit.cachefiles.backends._generate_file
celery_worker_1   | 
celery_worker_1   | [2022-05-18 23:47:50,350: INFO/MainProcess] Connected to redis://:**@redis_broker:6379/0
celery_worker_1   | [2022-05-18 23:47:50,368: INFO/MainProcess] mingle: searching for neighbors
celery_worker_1   | [2022-05-18 23:47:51,398: INFO/MainProcess] mingle: all alone
celery_worker_1   | [2022-05-18 23:47:51,460: INFO/MainProcess] celery@c65466b685e0 ready.
celery_worker_1   | [2022-05-18 23:47:54,326: INFO/MainProcess] Events of group {task} enabled by remote.
bookwyrm_dev-tools_1 exited with code 0
redis_activity_1  | 1:C 18 May 2022 23:47:36.295 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
redis_activity_1  | 1:C 18 May 2022 23:47:36.295 # Redis version=7.0.0, bits=64, commit=00000000, modified=0, pid=1, just started
redis_activity_1  | 1:C 18 May 2022 23:47:36.296 # Configuration loaded
redis_activity_1  | 1:M 18 May 2022 23:47:36.297 * monotonic clock: POSIX clock_gettime
redis_activity_1  | 1:M 18 May 2022 23:47:36.300 * Running mode=standalone, port=6379.
redis_activity_1  | 1:M 18 May 2022 23:47:36.300 # Server initialized
redis_activity_1  | 1:M 18 May 2022 23:47:36.300 # WARNING overcommit_memory is set to 0! Background save may fail under low memory condition. To fix this issue add 'vm.overcommit_memory = 1' to /etc/sysctl.conf and then reboot or run the command 'sysctl vm.overcommit_memory=1' for this to take effect.
redis_activity_1  | 1:M 18 May 2022 23:47:36.308 * Reading RDB base file on AOF loading...
redis_activity_1  | 1:M 18 May 2022 23:47:36.308 * Loading RDB produced by version 7.0.0
redis_activity_1  | 1:M 18 May 2022 23:47:36.308 * RDB age 3833 seconds
redis_activity_1  | 1:M 18 May 2022 23:47:36.308 * RDB memory usage when created 0.85 Mb
redis_activity_1  | 1:M 18 May 2022 23:47:36.308 * RDB is base AOF
redis_activity_1  | 1:M 18 May 2022 23:47:36.308 * Done loading RDB, keys loaded: 0, keys expired: 0.
redis_activity_1  | 1:M 18 May 2022 23:47:36.309 * DB loaded from base file appendonly.aof.1.base.rdb: 0.000 seconds
redis_activity_1  | 1:M 18 May 2022 23:47:36.309 * Ready to accept connections
nginx_1           | /docker-entrypoint.sh: /docker-entrypoint.d/ is not empty, will attempt to perform configuration
nginx_1           | /docker-entrypoint.sh: Looking for shell scripts in /docker-entrypoint.d/
nginx_1           | /docker-entrypoint.sh: Launching /docker-entrypoint.d/10-listen-on-ipv6-by-default.sh
nginx_1           | 10-listen-on-ipv6-by-default.sh: info: /etc/nginx/conf.d/default.conf is not a file or does not exist
nginx_1           | /docker-entrypoint.sh: Launching /docker-entrypoint.d/20-envsubst-on-templates.sh
nginx_1           | /docker-entrypoint.sh: Launching /docker-entrypoint.d/30-tune-worker-processes.sh
nginx_1           | /docker-entrypoint.sh: Configuration complete; ready for start up
nginx_1           | 2022/05/18 23:47:46 [notice] 1#1: using the "epoll" event method
nginx_1           | 2022/05/18 23:47:46 [notice] 1#1: nginx/1.21.6
nginx_1           | 2022/05/18 23:47:46 [notice] 1#1: built by gcc 10.2.1 20210110 (Debian 10.2.1-6) 
nginx_1           | 2022/05/18 23:47:46 [notice] 1#1: OS: Linux 5.15.0-1006-raspi
nginx_1           | 2022/05/18 23:47:46 [notice] 1#1: getrlimit(RLIMIT_NOFILE): 1048576:1048576
nginx_1           | 2022/05/18 23:47:46 [notice] 1#1: start worker processes
nginx_1           | 2022/05/18 23:47:46 [notice] 1#1: start worker process 22
nginx_1           | 2022/05/18 23:47:46 [notice] 1#1: start worker process 23
nginx_1           | 2022/05/18 23:47:46 [notice] 1#1: start worker process 24
flower_1          | [I 220518 23:47:49 command:152] Visit me at http://localhost:8888
flower_1          | [I 220518 23:47:49 command:159] Broker: redis://:**@redis_broker:6379/0
flower_1          | [I 220518 23:47:49 command:160] Registered tasks: 
flower_1          |     ['bookwyrm.activitypub.base_activity.set_related_field',
flower_1          |      'bookwyrm.activitystreams.add_book_statuses_task',
flower_1          |      'bookwyrm.activitystreams.add_status_task',
flower_1          |      'bookwyrm.activitystreams.add_user_statuses_task',
flower_1          |      'bookwyrm.activitystreams.handle_boost_task',
flower_1          |      'bookwyrm.activitystreams.populate_stream_task',
flower_1          |      'bookwyrm.activitystreams.remove_book_statuses_task',
flower_1          |      'bookwyrm.activitystreams.remove_status_task',
flower_1          |      'bookwyrm.activitystreams.remove_user_statuses_task',
flower_1          |      'bookwyrm.connectors.connector_manager.load_more_data',
flower_1          |      'bookwyrm.emailing.send_email',
flower_1          |      'bookwyrm.importers.importer.import_item_task',
flower_1          |      'bookwyrm.importers.importer.start_import_task',
flower_1          |      'bookwyrm.lists_stream.add_list_task',
flower_1          |      'bookwyrm.lists_stream.add_user_lists_task',
flower_1          |      'bookwyrm.lists_stream.populate_lists_task',
flower_1          |      'bookwyrm.lists_stream.remove_list_task',
flower_1          |      'bookwyrm.lists_stream.remove_user_lists_task',
flower_1          |      'bookwyrm.models.activitypub_mixin.broadcast_task',
flower_1          |      'bookwyrm.models.antispam.automod_task',
flower_1          |      'bookwyrm.models.user.get_remote_reviews',
flower_1          |      'bookwyrm.models.user.set_remote_server',
flower_1          |      'bookwyrm.preview_images.generate_edition_preview_image_task',
flower_1          |      'bookwyrm.preview_images.generate_site_preview_image_task',
flower_1          |      'bookwyrm.preview_images.generate_user_preview_image_task',
flower_1          |      'bookwyrm.suggested_users.bulk_add_instance_task',
flower_1          |      'bookwyrm.suggested_users.bulk_remove_instance_task',
flower_1          |      'bookwyrm.suggested_users.remove_suggestion_task',
flower_1          |      'bookwyrm.suggested_users.remove_user_task',
flower_1          |      'bookwyrm.suggested_users.rerank_suggestions_task',
flower_1          |      'bookwyrm.suggested_users.rerank_user_task',
flower_1          |      'bookwyrm.views.inbox.activity_task',
flower_1          |      'celery.accumulate',
flower_1          |      'celery.backend_cleanup',
flower_1          |      'celery.chain',
flower_1          |      'celery.chord',
flower_1          |      'celery.chord_unlock',
flower_1          |      'celery.chunks',
flower_1          |      'celery.group',
flower_1          |      'celery.map',
flower_1          |      'celery.starmap',
flower_1          |      'imagekit.cachefiles.backends._generate_file']
flower_1          | [I 220518 23:47:49 mixins:225] Connected to redis://:**@redis_broker:6379/0
flower_1          | [W 220518 23:47:50 inspector:42] Inspect method revoked failed
flower_1          | [W 220518 23:47:50 inspector:42] Inspect method conf failed
flower_1          | [W 220518 23:47:50 inspector:42] Inspect method scheduled failed
flower_1          | [W 220518 23:47:50 inspector:42] Inspect method active_queues failed
flower_1          | [W 220518 23:47:50 inspector:42] Inspect method registered failed
flower_1          | [W 220518 23:47:50 inspector:42] Inspect method stats failed
flower_1          | [W 220518 23:47:50 inspector:42] Inspect method reserved failed
flower_1          | [W 220518 23:47:50 inspector:42] Inspect method active failed
nginx_1           | 2022/05/18 23:47:46 [notice] 1#1: start worker process 25
web_1             | [2022-05-18 23:47:43 +0000] [1] [INFO] Starting gunicorn 20.0.4
web_1             | [2022-05-18 23:47:43 +0000] [1] [INFO] Listening at: http://0.0.0.0:8000 (1)
web_1             | [2022-05-18 23:47:43 +0000] [1] [INFO] Using worker: sync
web_1             | [2022-05-18 23:47:43 +0000] [6] [INFO] Booting worker with pid: 6
redis_broker_1    | 1:C 18 May 2022 23:47:36.109 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
redis_broker_1    | 1:C 18 May 2022 23:47:36.109 # Redis version=7.0.0, bits=64, commit=00000000, modified=0, pid=1, just started
redis_broker_1    | 1:C 18 May 2022 23:47:36.109 # Configuration loaded
redis_broker_1    | 1:M 18 May 2022 23:47:36.111 * monotonic clock: POSIX clock_gettime
redis_broker_1    | 1:M 18 May 2022 23:47:36.114 * Running mode=standalone, port=6379.
redis_broker_1    | 1:M 18 May 2022 23:47:36.115 # Server initialized
redis_broker_1    | 1:M 18 May 2022 23:47:36.115 # WARNING overcommit_memory is set to 0! Background save may fail under low memory condition. To fix this issue add 'vm.overcommit_memory = 1' to /etc/sysctl.conf and then reboot or run the command 'sysctl vm.overcommit_memory=1' for this to take effect.
redis_broker_1    | 1:M 18 May 2022 23:47:36.125 * Reading RDB base file on AOF loading...
redis_broker_1    | 1:M 18 May 2022 23:47:36.126 * Loading RDB produced by version 7.0.0
redis_broker_1    | 1:M 18 May 2022 23:47:36.126 * RDB age 3833 seconds
redis_broker_1    | 1:M 18 May 2022 23:47:36.127 * RDB memory usage when created 0.85 Mb
redis_broker_1    | 1:M 18 May 2022 23:47:36.127 * RDB is base AOF
redis_broker_1    | 1:M 18 May 2022 23:47:36.127 * Done loading RDB, keys loaded: 0, keys expired: 0.
redis_broker_1    | 1:M 18 May 2022 23:47:36.128 * DB loaded from base file appendonly.aof.1.base.rdb: 0.003 seconds
redis_broker_1    | 1:M 18 May 2022 23:47:36.130 * DB loaded from incr file appendonly.aof.1.incr.aof: 0.002 seconds
redis_broker_1    | 1:M 18 May 2022 23:47:36.130 * Ready to accept connections
redis_broker_1    | 1:M 18 May 2022 23:52:37.020 * 100 changes in 300 seconds. Saving...
redis_broker_1    | 1:M 18 May 2022 23:52:37.022 * Background saving started by pid 20
redis_broker_1    | 20:C 18 May 2022 23:52:37.028 * DB saved on disk
redis_broker_1    | 20:C 18 May 2022 23:52:37.029 * Fork CoW for RDB: current 0 MB, peak 0 MB, average 0 MB
redis_broker_1    | 1:M 18 May 2022 23:52:37.122 * Background saving terminated with success
db_1              | 2022-05-19 00:00:01.625 UTC [235] FATAL:  role "bookwyrm" does not exist

The FATAL error at the end did not appear for some time after starting the container. You can see from the logs that the logs cover approx 15 minutes. It has run for a further 15 minutes and there are no more log entries.

Any tips on where to start troubleshooting this?

[mouse-reeve]

Hm, it looks like the database may not have the correct bookwyrm user? I think this issue has come up in the past -- would you be down to ask in matrix room? You'll get quicker help there

I think the documentation is out of date when it says that you should be able to access the site at port 8001, although that doesn't help your core problems

[Neriderc]

Solved by running ./bw-dev migrate

[mouse-reeve]

Great, I'm glad to hear it!