Can't Upgrade from 0.5.6

[yottabit42]

Hi I'm on 0.5.6 but when I attempt to update to 1.0.0-beta4 the service never comes up. I am using TrueNAS with the TrueCharts deployment of Mealie; I don't have any intermediate versions to choose from.

I am open to trying to restore my backup into other intermediate versions to help the upgrade path if you think that is likely to help. Or I can provide my backup file.

Please advise on next steps for troubleshooting the upgrade, thanks!

[hay-kot]

 I am using TrueNAS with the TrueCharts deployment of Mealie; I don't have any intermediate versions to choose from

I don't really know what any of this means. The only officially supported deployment option is docker-compose.

Can you provide some more information on how you're deploying the containers, what env settings you've configured and the docker network configuration?

Are you deploying both containers?

What do you mean the server never comes up?

Do you have any relevant log files?

Thanks!

[yottabit42]

Thanks for your reply! TrueNAS is a storage server distribution that supports Helm charts for deploying Docker images in Kubernetes. TrueCharts is a third-party repository of a ton of Docker images, including Mealie.

The TrueNAS UI supports an upgrade function where the user only needs to select the version to deploy. The upgraded image is automatically downloaded and deployed. This is where I'm having problems. I attempted to upgrade a few days back, and when the container deployed the new version, I could not login. I suspected the problem was because they (TrueCharts) had changed some of the Helm chart options, and I moved to non-deprecated settings. But the containers never fully deploy, stuck in the deploying state, I think because the database conversion goes wrong.

I managed to copy the backup zips out of the Mealie data storage, and thought I would just install the latest version from scratch, and then restore the backup from the Mealie UI. But this is where I first realized that Mealie only supports restoring backups made from the same version, so this wasn't goiing to work.

Next I deployed a second Mealie instance using the old version (0.5.6) and successfully restored my backup. But attempts to upgrade this instance result in the container never deploying successfully. However, deploying a new instance with the same version is successful, so I can only assume that the database migration process from old to new, in the upgrade workflow, is causing a problem. If I rollback to the prior version of the instance it still works fine.

Here is the log I see when deploying the old version (success):

2022-09-30 14:03:44.693971+00:00Production
2022-09-30 14:03:44.740077+00:002022-09-30T14:03:44.740077384Z
2022-09-30 14:03:44.740130+00:00User uid:    568
2022-09-30 14:03:44.740139+00:00User gid:    568
2022-09-30 14:03:44.740146+00:002022-09-30T14:03:44.740146777Z
2022-09-30 14:03:47.508202+00:00Database Exists
2022-09-30 14:03:49.647160+00:00INFO: 30-Sep-22 09:03:49 	Checking for Images to Minify...
2022-09-30 14:03:49.647881+00:00INFO: 30-Sep-22 09:03:49 	Finished Minification Check
2022-09-30 14:03:49.797404+00:00{"level":"info","ts":1664546629.7970383,"msg":"using provided configuration","config_file":"/app/Caddyfile","config_adapter":""}
2022-09-30 14:03:49.801531+00:00{"level":"warn","ts":1664546629.8012736,"logger":"admin","msg":"admin endpoint disabled"}
2022-09-30 14:03:49.802030+00:00{"level":"info","ts":1664546629.801887,"logger":"tls.cache.maintenance","msg":"started background certificate maintenance","cache":"0xc0001596c0"}
2022-09-30 14:03:49.803017+00:00{"level":"info","ts":1664546629.802789,"logger":"tls","msg":"cleaning storage unit","description":"FileStorage:/root/.local/share/caddy"}
2022-09-30 14:03:49.803094+00:00{"level":"info","ts":1664546629.8028424,"logger":"tls","msg":"finished cleaning storage units"}
2022-09-30 14:03:49.803681+00:00{"level":"info","ts":1664546629.803542,"msg":"autosaved config (load with --resume flag)","file":"/root/.config/caddy/autosave.json"}
2022-09-30 14:03:49.803704+00:00{"level":"info","ts":1664546629.8035529,"msg":"serving initial configuration"}
2022-09-30 14:03:49.804018+00:00Successfully started Caddy (pid=44) - Caddy is running in the background
2022-09-30 14:03:50.396234+00:00---- Gunicorn Configuration ---- {
2022-09-30 14:03:50.396270+00:00"loglevel": "info",
2022-09-30 14:03:50.396278+00:00"workers": 1,
2022-09-30 14:03:50.396296+00:00"bind": "127.0.0.1:80",
2022-09-30 14:03:50.396303+00:00"graceful_timeout": 120,
2022-09-30 14:03:50.396309+00:00"timeout": 120,
2022-09-30 14:03:50.396315+00:00"keepalive": 5,
2022-09-30 14:03:50.396321+00:00"errorlog": "-",
2022-09-30 14:03:50.396332+00:00"accesslog": "-",
2022-09-30 14:03:50.396339+00:00"workers_per_core": 1.0,
2022-09-30 14:03:50.396344+00:00"use_max_workers": "1",
2022-09-30 14:03:50.396350+00:00"host": "127.0.0.1",
2022-09-30 14:03:50.396356+00:00"port": "80"
2022-09-30 14:03:50.396362+00:00}
2022-09-30 14:03:52.851377+00:00[2022-09-30 09:03:52 -0500] [60] [INFO] Starting gunicorn 20.1.0
2022-09-30 14:03:52.852532+00:00[2022-09-30 09:03:52 -0500] [60] [INFO] Listening at: http://0.0.0.0:9000 (60)
2022-09-30 14:03:52.852559+00:00[2022-09-30 09:03:52 -0500] [60] [INFO] Using worker: uvicorn.workers.UvicornWorker
2022-09-30 14:03:52.860937+00:00[2022-09-30 09:03:52 -0500] [61] [INFO] Booting worker with pid: 61
2022-09-30 14:03:52.900496+00:00[2022-09-30 09:03:52 -0500] [61] [INFO] Started server process [61]
2022-09-30 14:03:52.900529+00:00[2022-09-30 09:03:52 -0500] [61] [INFO] Waiting for application startup.
2022-09-30 14:03:53.035064+00:00/opt/pysetup/.venv/lib/python3.9/site-packages/apscheduler/util.py:95: PytzUsageWarning: The zone attribute is specific to pytz's interface; please migrate to a new time zone provider. For more details on how to do so, see https://pytz-deprecation-shim.readthedocs.io/en/latest/migration.html
2022-09-30 14:03:53.035114+00:00if obj.zone == 'local':
2022-09-30 14:03:53.035417+00:00INFO: 30-Sep-22 09:03:53 	Adding job tentatively -- it will be properly scheduled when the scheduler starts
2022-09-30 14:03:53.035505+00:00INFO: 30-Sep-22 09:03:53 	Adding job tentatively -- it will be properly scheduled when the scheduler starts
2022-09-30 14:03:53.035705+00:00INFO: 30-Sep-22 09:03:53 	----INIT SCHEDULE OBJECT-----
2022-09-30 14:03:53.039616+00:00INFO: 30-Sep-22 09:03:53 	Adding job tentatively -- it will be properly scheduled when the scheduler starts
2022-09-30 14:03:53.136411+00:00INFO: 30-Sep-22 09:03:53 	Adding job tentatively -- it will be properly scheduled when the scheduler starts
2022-09-30 14:03:53.136820+00:00Pending jobs:
2022-09-30 14:03:53.137230+00:00purge_events_database (trigger: interval[1 day, 0:00:00], pending)
2022-09-30 14:03:53.137280+00:00update_webhook_schedule (trigger: interval[0:30:00], pending)
2022-09-30 14:03:53.137324+00:00backups (trigger: cron[hour='0', minute='0'], pending)
2022-09-30 14:03:53.137343+00:00Home (trigger: cron[hour='0', minute='0'], pending)
2022-09-30 14:03:53.137362+00:00INFO: 30-Sep-22 09:03:53 	None
2022-09-30 14:03:53.143883+00:00/opt/pysetup/.venv/lib/python3.9/site-packages/apscheduler/triggers/interval.py:66: PytzUsageWarning: The normalize method is no longer necessary, as this time zone supports the fold attribute (PEP 495). For more details on migrating to a PEP 495-compliant implementation, see https://pytz-deprecation-shim.readthedocs.io/en/latest/migration.html
2022-09-30 14:03:53.143957+00:00return self.timezone.normalize(next_fire_time)
2022-09-30 14:03:53.145987+00:00INFO: 30-Sep-22 09:03:53 	Added job "purge_events_database" to job store "default"
2022-09-30 14:03:53.147001+00:00INFO: 30-Sep-22 09:03:53 	Added job "update_webhook_schedule" to job store "default"
2022-09-30 14:03:53.147320+00:00/opt/pysetup/.venv/lib/python3.9/site-packages/apscheduler/triggers/cron/__init__.py:146: PytzUsageWarning: The normalize method is no longer necessary, as this time zone supports the fold attribute (PEP 495). For more details on migrating to a PEP 495-compliant implementation, see https://pytz-deprecation-shim.readthedocs.io/en/latest/migration.html
2022-09-30 14:03:53.147379+00:00return self.timezone.normalize(dateval + difference), fieldnum
2022-09-30 14:03:53.148464+00:00INFO: 30-Sep-22 09:03:53 	Added job "backups" to job store "default"
2022-09-30 14:03:53.149521+00:00INFO: 30-Sep-22 09:03:53 	Added job "Home" to job store "default"
2022-09-30 14:03:53.149552+00:00INFO: 30-Sep-22 09:03:53 	Scheduler started
2022-09-30 14:03:53.152102+00:00INFO: 30-Sep-22 09:03:53 	-----SYSTEM STARTUP----- 
2022-09-30 14:03:53.152120+00:002022-09-30T14:03:53.152120535Z
2022-09-30 14:03:53.152153+00:00INFO: 30-Sep-22 09:03:53 	------APP SETTINGS------
2022-09-30 14:03:53.162916+00:00INFO: 30-Sep-22 09:03:53 	{
2022-09-30 14:03:53.162947+00:00"PRODUCTION": true,
2022-09-30 14:03:53.162955+00:00"BASE_URL": "http://localhost:8080",
2022-09-30 14:03:53.162961+00:00"IS_DEMO": false,
2022-09-30 14:03:53.162967+00:00"API_PORT": 9000,
2022-09-30 14:03:53.162973+00:00"API_DOCS": true,
2022-09-30 14:03:53.162984+00:00"DB_ENGINE": "postgres",
2022-09-30 14:03:53.162990+00:00"POSTGRES_SERVER": "mealie-9-0-3-postgresql",
2022-09-30 14:03:53.162996+00:00"POSTGRES_PORT": "5432",
2022-09-30 14:03:53.163002+00:00"POSTGRES_DB": "mealie",
2022-09-30 14:03:53.163008+00:00"DB_URL_PUBLIC": "postgresql://*****:*****@mealie-9-0-3-postgresql:5432/mealie",
2022-09-30 14:03:53.163018+00:00"DEFAULT_GROUP": "Home",
2022-09-30 14:03:53.163024+00:00"DEFAULT_EMAIL": "[email protected]",
2022-09-30 14:03:53.163030+00:00"LDAP_AUTH_ENABLED": false,
2022-09-30 14:03:53.163036+00:00"LDAP_SERVER_URL": null,
2022-09-30 14:03:53.163050+00:00"LDAP_BIND_TEMPLATE": null,
2022-09-30 14:03:53.163056+00:00"LDAP_ADMIN_FILTER": null,
2022-09-30 14:03:53.163062+00:00"TOKEN_TIME": 2,
2022-09-30 14:03:53.163068+00:00"RECIPE_PUBLIC": true,
2022-09-30 14:03:53.163074+00:00"RECIPE_SHOW_NUTRITION": true,
2022-09-30 14:03:53.163088+00:00"RECIPE_SHOW_ASSETS": true,
2022-09-30 14:03:53.163094+00:00"RECIPE_LANDSCAPE_VIEW": true,
2022-09-30 14:03:53.163100+00:00"RECIPE_DISABLE_COMMENTS": false,
2022-09-30 14:03:53.163106+00:00"RECIPE_DISABLE_AMOUNT": false,
2022-09-30 14:03:53.163112+00:00"AUTO_BACKUP_ENABLED": false,
2022-09-30 14:03:53.163122+00:00"SCHEDULER_DATABASE": "sqlite:////app/data/scheduler.db"
2022-09-30 14:03:53.163129+00:00}
2022-09-30 14:03:53.176065+00:00None
2022-09-30 14:03:53.176146+00:00[2022-09-30 09:03:53 -0500] [61] [INFO] Application startup complete.

And here is the database container log:

2022-09-30 14:03:31.764935+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.76 �[0m
2022-09-30 14:03:31.766219+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.76 �[0m�[1mWelcome to the Bitnami postgresql container�[0m
2022-09-30 14:03:31.767668+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.76 �[0mSubscribe to project updates by watching �[1mhttps://github.com/bitnami/containers�[0m
2022-09-30 14:03:31.768962+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.76 �[0mSubmit issues and feature requests at �[1mhttps://github.com/bitnami/containers/issues�[0m
2022-09-30 14:03:31.770369+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.76 �[0m
2022-09-30 14:03:31.785958+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.78 �[0m�[38;5;2mINFO �[0m ==> ** Starting PostgreSQL setup **
2022-09-30 14:03:31.806536+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.80 �[0m�[38;5;2mINFO �[0m ==> Validating settings in POSTGRESQL_* env vars..
2022-09-30 14:03:31.812419+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.81 �[0m�[38;5;2mINFO �[0m ==> Cleaning stale /bitnami/postgresql/data/postmaster.pid file
2022-09-30 14:03:31.815901+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.81 �[0m�[38;5;2mINFO �[0m ==> Loading custom pre-init scripts...
2022-09-30 14:03:31.822439+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.82 �[0m�[38;5;2mINFO �[0m ==> Initializing PostgreSQL database...
2022-09-30 14:03:31.838858+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.83 �[0m�[38;5;2mINFO �[0m ==> pg_hba.conf file not detected. Generating it...
2022-09-30 14:03:31.840364+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.83 �[0m�[38;5;2mINFO �[0m ==> Generating local authentication configuration
2022-09-30 14:03:31.860129+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.85 �[0m�[38;5;2mINFO �[0m ==> Deploying PostgreSQL with persisted data...
2022-09-30 14:03:31.886896+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.88 �[0m�[38;5;2mINFO �[0m ==> Configuring replication parameters
2022-09-30 14:03:31.912316+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.91 �[0m�[38;5;2mINFO �[0m ==> Configuring fsync
2022-09-30 14:03:31.918578+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.91 �[0m�[38;5;2mINFO �[0m ==> Configuring synchronous_replication
2022-09-30 14:03:31.932956+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.93 �[0m�[38;5;2mINFO �[0m ==> Loading custom scripts...
2022-09-30 14:03:31.936721+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.93 �[0m�[38;5;2mINFO �[0m ==> Enabling remote connections
2022-09-30 14:03:31.944817+00:002022-09-30T14:03:31.944817010Z
2022-09-30 14:03:31.944867+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.94 �[0m�[38;5;2mINFO �[0m ==> ** PostgreSQL setup finished! **
2022-09-30 14:03:31.962295+00:00�[38;5;6mpostgresql �[38;5;5m14:03:31.96 �[0m�[38;5;2mINFO �[0m ==> ** Starting PostgreSQL **
2022-09-30 14:03:31.995525+00:002022-09-30 14:03:31.995 GMT [1] LOG:  pgaudit extension initialized
2022-09-30 14:03:32.000001+00:002022-09-30 14:03:31.999 GMT [1] LOG:  starting PostgreSQL 14.5 on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2022-09-30 14:03:32.000494+00:002022-09-30 14:03:32.000 GMT [1] LOG:  listening on IPv4 address "0.0.0.0", port 5432
2022-09-30 14:03:32.000551+00:002022-09-30 14:03:32.000 GMT [1] LOG:  listening on IPv6 address "::", port 5432
2022-09-30 14:03:32.000613+00:002022-09-30 14:03:32.000 GMT [1] LOG:  listening on Unix socket "/tmp/.s.PGSQL.5432"
2022-09-30 14:03:32.005024+00:002022-09-30 14:03:32.004 GMT [87] LOG:  database system was interrupted; last known up at 2022-09-30 04:04:50 GMT
2022-09-30 14:03:32.033051+00:002022-09-30 14:03:32.032 GMT [87] LOG:  database system was not properly shut down; automatic recovery in progress
2022-09-30 14:03:32.034106+00:002022-09-30 14:03:32.033 GMT [87] LOG:  redo starts at 0/198CFE8
2022-09-30 14:03:32.034148+00:002022-09-30 14:03:32.034 GMT [87] LOG:  invalid record length at 0/198D0D0: wanted 24, got 0
2022-09-30 14:03:32.034158+00:002022-09-30 14:03:32.034 GMT [87] LOG:  redo done at 0/198D098 system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2022-09-30 14:03:32.039838+00:002022-09-30 14:03:32.039 GMT [1] LOG:  database system is ready to accept connections

And then if I upgrade the container to the latest, this is the resulting very short log:

2022-09-30 14:10:51.664057+00:00{"level":"info","ts":1664547051.66376,"msg":"using provided configuration","config_file":"/app/Caddyfile","config_adapter":""}
2022-09-30 14:10:51.666506+00:00{"level":"warn","ts":1664547051.6664305,"msg":"Caddyfile input is not formatted; run the 'caddy fmt' command to fix inconsistencies","adapter":"caddyfile","file":"/app/Caddyfile","line":36}
2022-09-30 14:10:51.667040+00:00{"level":"warn","ts":1664547051.666969,"logger":"admin","msg":"admin endpoint disabled"}
2022-09-30 14:10:51.667291+00:00{"level":"warn","ts":1664547051.6672237,"logger":"http","msg":"automatic HTTPS is completely disabled for server","server_name":"srv0"}
2022-09-30 14:10:51.668090+00:00{"level":"info","ts":1664547051.6680472,"logger":"tls.cache.maintenance","msg":"started background certificate maintenance","cache":"0xc0003b47e0"}
2022-09-30 14:10:51.669125+00:00{"level":"info","ts":1664547051.6690557,"logger":"tls","msg":"cleaning storage unit","description":"FileStorage:/root/.local/share/caddy"}
2022-09-30 14:10:51.669172+00:00{"level":"info","ts":1664547051.669133,"logger":"tls","msg":"finished cleaning storage units"}
2022-09-30 14:10:51.669473+00:00{"level":"info","ts":1664547051.6694007,"msg":"autosaved config (load with --resume flag)","file":"/root/.config/caddy/autosave.json"}
2022-09-30 14:10:51.669494+00:00{"level":"info","ts":1664547051.6694233,"msg":"serving initial configuration"}
2022-09-30 14:10:51.669782+00:00Successfully started Caddy (pid=22) - Caddy is running in the background
2022-09-30 14:10:52.102006+00:00yarn run v1.22.19
2022-09-30 14:10:52.138589+00:00$ nuxt start -p 3001
2022-09-30 14:10:52.970445+00:00ℹ [HPM] Proxy created: api  -> http://localhost:9000
2022-09-30 14:10:52.972816+00:00ℹ [HPM] Proxy rewrite rule created: "api" ~> "/api"
2022-09-30 14:10:52.973526+00:00ℹ [HPM] Proxy created: /api  -> http://localhost:9000
2022-09-30 14:10:53.803892+00:00ℹ Listening on: http://172.17.0.138:3001/

Meanwhile the api container on this version is not happy:

2022-09-30 14:12:45.373964+00:00Production
2022-09-30 14:12:45.414859+00:002022-09-30T14:12:45.414859901Z
2022-09-30 14:12:45.414909+00:00User uid:    568
2022-09-30 14:12:45.414928+00:00User gid:    568
2022-09-30 14:12:45.414944+00:002022-09-30T14:12:45.414944397Z
2022-09-30 14:12:47.884575+00:00INFO: 30-Sep-22 09:12:47 	Database connection established.
2022-09-30 14:12:47.898969+00:00INFO: 30-Sep-22 09:12:47 	Context impl PostgresqlImpl.
2022-09-30 14:12:47.899028+00:00INFO: 30-Sep-22 09:12:47 	Will assume transactional DDL.
2022-09-30 14:12:47.911774+00:00INFO: 30-Sep-22 09:12:47 	Migration needed. Performing migration...
2022-09-30 14:12:47.925281+00:00INFO: 30-Sep-22 09:12:47 	Context impl PostgresqlImpl.
2022-09-30 14:12:47.925316+00:00INFO: 30-Sep-22 09:12:47 	Will assume transactional DDL.
2022-09-30 14:12:47.938371+00:00INFO: 30-Sep-22 09:12:47 	Running upgrade  -> 6b0f5f32d602, Initial tables
2022-09-30 14:12:47.951743+00:00INFO: 30-Sep-22 09:12:47 	Running upgrade 6b0f5f32d602 -> 263dd6707191, convert quantity from integer to float
2022-09-30 14:12:47.960814+00:00INFO: 30-Sep-22 09:12:47 	Running upgrade 263dd6707191 -> f1a2dbee5fe9, Add original_text column to recipes_ingredients
2022-09-30 14:12:47.962267+00:00INFO: 30-Sep-22 09:12:47 	Running upgrade f1a2dbee5fe9 -> 59eb59135381, add tags to cookbooks
2022-09-30 14:12:47.965091+00:00Traceback (most recent call last):
2022-09-30 14:12:47.965106+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 1819, in _execute_context
2022-09-30 14:12:47.965729+00:00self.dialect.do_execute(
2022-09-30 14:12:47.965752+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/default.py", line 732, in do_execute
2022-09-30 14:12:47.966073+00:00cursor.execute(statement, parameters)
2022-09-30 14:12:47.966097+00:00psycopg2.errors.UndefinedTable: relation "cookbooks" does not exist
2022-09-30 14:12:47.966105+00:002022-09-30T14:12:47.966105171Z
2022-09-30 14:12:47.966111+00:002022-09-30T14:12:47.966111041Z
2022-09-30 14:12:47.966116+00:00The above exception was the direct cause of the following exception:
2022-09-30 14:12:47.966122+00:002022-09-30T14:12:47.966122882Z
2022-09-30 14:12:47.966132+00:00Traceback (most recent call last):
2022-09-30 14:12:47.966139+00:00File "/app/mealie/db/init_db.py", line 108, in <module>
2022-09-30 14:12:47.966165+00:00main()
2022-09-30 14:12:47.966184+00:00File "/app/mealie/db/init_db.py", line 94, in main
2022-09-30 14:12:47.966251+00:00command.upgrade(alembic_cfg, "head")
2022-09-30 14:12:47.966268+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/command.py", line 322, in upgrade
2022-09-30 14:12:47.966429+00:00script.run_env()
2022-09-30 14:12:47.966440+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/script/base.py", line 569, in run_env
2022-09-30 14:12:47.966697+00:00util.load_python_file(self.dir, "env.py")
2022-09-30 14:12:47.966715+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/util/pyfiles.py", line 94, in load_python_file
2022-09-30 14:12:47.966794+00:00module = load_module_py(module_id, path)
2022-09-30 14:12:47.966815+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/util/pyfiles.py", line 110, in load_module_py
2022-09-30 14:12:47.966869+00:00spec.loader.exec_module(module)  # type: ignore
2022-09-30 14:12:47.966879+00:00File "<frozen importlib._bootstrap_external>", line 883, in exec_module
2022-09-30 14:12:47.967027+00:00File "<frozen importlib._bootstrap>", line 241, in _call_with_frames_removed
2022-09-30 14:12:47.967193+00:00File "/app/alembic/env.py", line 77, in <module>
2022-09-30 14:12:47.967216+00:00run_migrations_online()
2022-09-30 14:12:47.967223+00:00File "/app/alembic/env.py", line 71, in run_migrations_online
2022-09-30 14:12:47.967284+00:00context.run_migrations()
2022-09-30 14:12:47.967296+00:00File "<string>", line 8, in run_migrations
2022-09-30 14:12:47.967422+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/runtime/environment.py", line 853, in run_migrations
2022-09-30 14:12:47.967766+00:00self.get_context().run_migrations(**kw)
2022-09-30 14:12:47.967777+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/runtime/migration.py", line 623, in run_migrations
2022-09-30 14:12:47.968037+00:00step.migration_fn(**kw)
2022-09-30 14:12:47.968056+00:00File "/app/alembic/versions/2022-03-31-19.19.55_59eb59135381_add_tags_to_cookbooks.py", line 22, in upgrade
2022-09-30 14:12:47.968081+00:00op.create_table(
2022-09-30 14:12:47.968092+00:00File "<string>", line 8, in create_table
2022-09-30 14:12:47.968163+00:00File "<string>", line 3, in create_table
2022-09-30 14:12:47.968226+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/operations/ops.py", line 1254, in create_table
2022-09-30 14:12:47.968646+00:00return operations.invoke(op)
2022-09-30 14:12:47.968660+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/operations/base.py", line 394, in invoke
2022-09-30 14:12:47.968814+00:00return fn(self, operation)
2022-09-30 14:12:47.968825+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/operations/toimpl.py", line 114, in create_table
2022-09-30 14:12:47.968918+00:00operations.impl.create_table(table)
2022-09-30 14:12:47.968938+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/ddl/impl.py", line 354, in create_table
2022-09-30 14:12:47.969089+00:00self._exec(schema.CreateTable(table))
2022-09-30 14:12:47.969103+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/ddl/impl.py", line 195, in _exec
2022-09-30 14:12:47.969181+00:00return conn.execute(construct, multiparams)
2022-09-30 14:12:47.969198+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 1306, in execute
2022-09-30 14:12:47.969480+00:00return meth(self, multiparams, params, _EMPTY_EXECUTION_OPTS)
2022-09-30 14:12:47.969490+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/sql/ddl.py", line 80, in _execute_on_connection
2022-09-30 14:12:47.969651+00:00return connection._execute_ddl(
2022-09-30 14:12:47.969663+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 1398, in _execute_ddl
2022-09-30 14:12:47.969959+00:00ret = self._execute_context(
2022-09-30 14:12:47.969973+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 1862, in _execute_context
2022-09-30 14:12:47.970319+00:00self._handle_dbapi_exception(
2022-09-30 14:12:47.970330+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 2043, in _handle_dbapi_exception
2022-09-30 14:12:47.970708+00:00util.raise_(
2022-09-30 14:12:47.970727+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/util/compat.py", line 208, in raise_
2022-09-30 14:12:47.970865+00:00raise exception
2022-09-30 14:12:47.970875+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 1819, in _execute_context
2022-09-30 14:12:47.971219+00:00self.dialect.do_execute(
2022-09-30 14:12:47.971235+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/default.py", line 732, in do_execute
2022-09-30 14:12:47.971426+00:00cursor.execute(statement, parameters)
2022-09-30 14:12:47.971460+00:00sqlalchemy.exc.ProgrammingError: (psycopg2.errors.UndefinedTable) relation "cookbooks" does not exist
2022-09-30 14:12:47.971477+00:002022-09-30T14:12:47.971477883Z
2022-09-30 14:12:47.971484+00:00[SQL: 
2022-09-30 14:12:47.971490+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:12:47.971496+00:00cookbook_id UUID, 
2022-09-30 14:12:47.971502+00:00tag_id UUID, 
2022-09-30 14:12:47.971507+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:12:47.971518+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:12:47.971524+00:00)
2022-09-30 14:12:47.971530+00:002022-09-30T14:12:47.971530047Z
2022-09-30 14:12:47.971535+00:00]
2022-09-30 14:12:47.971541+00:00(Background on this error at: https://sqlalche.me/e/14/f405)

That looks like a database conversion problem. And here is the database container log:

2022-09-30 14:10:31.809722+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.80 �[0m
2022-09-30 14:10:31.811066+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.81 �[0m�[1mWelcome to the Bitnami postgresql container�[0m
2022-09-30 14:10:31.812321+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.81 �[0mSubscribe to project updates by watching �[1mhttps://github.com/bitnami/containers�[0m
2022-09-30 14:10:31.813666+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.81 �[0mSubmit issues and feature requests at �[1mhttps://github.com/bitnami/containers/issues�[0m
2022-09-30 14:10:31.814952+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.81 �[0m
2022-09-30 14:10:31.829457+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.82 �[0m�[38;5;2mINFO �[0m ==> ** Starting PostgreSQL setup **
2022-09-30 14:10:31.849065+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.84 �[0m�[38;5;2mINFO �[0m ==> Validating settings in POSTGRESQL_* env vars..
2022-09-30 14:10:31.859750+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.85 �[0m�[38;5;2mINFO �[0m ==> Cleaning stale /bitnami/postgresql/data/postmaster.pid file
2022-09-30 14:10:31.863596+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.86 �[0m�[38;5;2mINFO �[0m ==> Loading custom pre-init scripts...
2022-09-30 14:10:31.870891+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.87 �[0m�[38;5;2mINFO �[0m ==> Initializing PostgreSQL database...
2022-09-30 14:10:31.892481+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.89 �[0m�[38;5;2mINFO �[0m ==> pg_hba.conf file not detected. Generating it...
2022-09-30 14:10:31.894146+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.89 �[0m�[38;5;2mINFO �[0m ==> Generating local authentication configuration
2022-09-30 14:10:31.922914+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.92 �[0m�[38;5;2mINFO �[0m ==> Deploying PostgreSQL with persisted data...
2022-09-30 14:10:31.951605+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.95 �[0m�[38;5;2mINFO �[0m ==> Configuring replication parameters
2022-09-30 14:10:31.980877+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.98 �[0m�[38;5;2mINFO �[0m ==> Configuring fsync
2022-09-30 14:10:31.988671+00:00�[38;5;6mpostgresql �[38;5;5m14:10:31.98 �[0m�[38;5;2mINFO �[0m ==> Configuring synchronous_replication
2022-09-30 14:10:32.007213+00:00�[38;5;6mpostgresql �[38;5;5m14:10:32.00 �[0m�[38;5;2mINFO �[0m ==> Loading custom scripts...
2022-09-30 14:10:32.011840+00:00�[38;5;6mpostgresql �[38;5;5m14:10:32.01 �[0m�[38;5;2mINFO �[0m ==> Enabling remote connections
2022-09-30 14:10:32.023758+00:00�[38;5;6mpostgresql �[38;5;5m14:10:32.02 �[0m�[38;5;2mINFO �[0m ==> ** PostgreSQL setup finished! **
2022-09-30 14:10:32.023818+00:002022-09-30T14:10:32.023818360Z
2022-09-30 14:10:32.047082+00:00�[38;5;6mpostgresql �[38;5;5m14:10:32.04 �[0m�[38;5;2mINFO �[0m ==> ** Starting PostgreSQL **
2022-09-30 14:10:32.097728+00:002022-09-30 14:10:32.097 GMT [1] LOG:  pgaudit extension initialized
2022-09-30 14:10:32.102652+00:002022-09-30 14:10:32.102 GMT [1] LOG:  starting PostgreSQL 14.5 on x86_64-pc-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit
2022-09-30 14:10:32.103189+00:002022-09-30 14:10:32.103 GMT [1] LOG:  listening on IPv4 address "0.0.0.0", port 5432
2022-09-30 14:10:32.103209+00:002022-09-30 14:10:32.103 GMT [1] LOG:  listening on IPv6 address "::", port 5432
2022-09-30 14:10:32.103399+00:002022-09-30 14:10:32.103 GMT [1] LOG:  listening on Unix socket "/tmp/.s.PGSQL.5432"
2022-09-30 14:10:32.108052+00:002022-09-30 14:10:32.107 GMT [87] LOG:  database system was interrupted; last known up at 2022-09-30 14:08:32 GMT
2022-09-30 14:10:32.136182+00:002022-09-30 14:10:32.136 GMT [87] LOG:  database system was not properly shut down; automatic recovery in progress
2022-09-30 14:10:32.137290+00:002022-09-30 14:10:32.137 GMT [87] LOG:  redo starts at 0/198D650
2022-09-30 14:10:32.137311+00:002022-09-30 14:10:32.137 GMT [87] LOG:  invalid record length at 0/198D738: wanted 24, got 0
2022-09-30 14:10:32.137319+00:002022-09-30 14:10:32.137 GMT [87] LOG:  redo done at 0/198D700 system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2022-09-30 14:10:32.143099+00:002022-09-30 14:10:32.143 GMT [1] LOG:  database system is ready to accept connections
2022-09-30 14:10:57.309499+00:002022-09-30 14:10:57.309 GMT [129] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:10:57.309583+00:002022-09-30 14:10:57.309 GMT [129] STATEMENT:  
2022-09-30 14:10:57.309640+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:10:57.309663+00:00cookbook_id UUID, 
2022-09-30 14:10:57.309683+00:00tag_id UUID, 
2022-09-30 14:10:57.309703+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:10:57.309725+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:10:57.309784+00:00)
2022-09-30 14:10:57.309808+00:002022-09-30T14:10:57.309808762Z
2022-09-30 14:10:57.309825+00:002022-09-30T14:10:57.309825506Z
2022-09-30 14:11:04.321279+00:002022-09-30 14:11:04.321 GMT [133] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:11:04.321392+00:002022-09-30 14:11:04.321 GMT [133] STATEMENT:  
2022-09-30 14:11:04.321455+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:11:04.321479+00:00cookbook_id UUID, 
2022-09-30 14:11:04.321536+00:00tag_id UUID, 
2022-09-30 14:11:04.321562+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:11:04.321581+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:11:04.321616+00:00)
2022-09-30 14:11:04.321635+00:002022-09-30T14:11:04.321635886Z
2022-09-30 14:11:04.321653+00:002022-09-30T14:11:04.321653883Z
2022-09-30 14:11:25.735257+00:002022-09-30 14:11:25.735 GMT [169] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:11:25.735347+00:002022-09-30 14:11:25.735 GMT [169] STATEMENT:  
2022-09-30 14:11:25.735450+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:11:25.735478+00:00cookbook_id UUID, 
2022-09-30 14:11:25.735498+00:00tag_id UUID, 
2022-09-30 14:11:25.735517+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:11:25.735570+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:11:25.735591+00:00)
2022-09-30 14:11:25.735609+00:002022-09-30T14:11:25.735609013Z
2022-09-30 14:11:25.735626+00:002022-09-30T14:11:25.735626583Z
2022-09-30 14:11:56.763715+00:002022-09-30 14:11:56.763 GMT [238] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:11:56.763774+00:002022-09-30 14:11:56.763 GMT [238] STATEMENT:  
2022-09-30 14:11:56.763785+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:11:56.763793+00:00cookbook_id UUID, 
2022-09-30 14:11:56.763800+00:00tag_id UUID, 
2022-09-30 14:11:56.763807+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:11:56.763821+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:11:56.763828+00:00)
2022-09-30 14:11:56.763835+00:002022-09-30T14:11:56.763835079Z
2022-09-30 14:11:56.763841+00:002022-09-30T14:11:56.763841547Z
2022-09-30 14:12:47.964120+00:002022-09-30 14:12:47.963 GMT [324] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:12:47.964177+00:002022-09-30 14:12:47.963 GMT [324] STATEMENT:  
2022-09-30 14:12:47.964189+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:12:47.964196+00:00cookbook_id UUID, 
2022-09-30 14:12:47.964203+00:00tag_id UUID, 
2022-09-30 14:12:47.964209+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:12:47.964229+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:12:47.964247+00:00)
2022-09-30 14:12:47.964263+00:002022-09-30T14:12:47.964263919Z
2022-09-30 14:12:47.964271+00:002022-09-30T14:12:47.964271290Z
2022-09-30 14:14:20.645606+00:002022-09-30 14:14:20.645 GMT [478] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:14:20.645668+00:002022-09-30 14:14:20.645 GMT [478] STATEMENT:  
2022-09-30 14:14:20.645679+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:14:20.645686+00:00cookbook_id UUID, 
2022-09-30 14:14:20.645693+00:00tag_id UUID, 
2022-09-30 14:14:20.645700+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:14:20.645714+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:14:20.645722+00:00)
2022-09-30 14:14:20.645729+00:002022-09-30T14:14:20.645729183Z
2022-09-30 14:14:20.645735+00:002022-09-30T14:14:20.645735962Z

This setup is complicated, and I don't have direct control over these Helm charts that TrueCharts is publishing. It may be that you can't get enough information from these logs to debug. I'm not sure.

I think I will just keep the old instance running at the same time as the new instance, and just manually re-populate all of the recipes from the old to new, unless there is something obvious you see here that can help.

I opened an issue with TrueCharts, in case it is related to their reworking of the helm chart needed to support the new Mealie version.

Thanks for your time!

[yottabit42]

It turns out that TrueCharts considers this a "double breaking change" due to a breaking change in their Helm chart and a breaking change upstream in Mealie.

So it seems I only have three options here:

1. Keep using the old version
2. Install the old version of Mealie manually, restore my database, then upgrade Mealie, then export my database, and finally import the new database into the latest TrueCharts instance
3. Manually add my recipes from the old version to the new version

I think for now I will just go with option 3. It's only a hundred or so recipes... probably take less time to manually transfer them than to go through option 2.

[hay-kot]

If you get a v1 instance up and running, you can directly import your v0.5.6 backup.

https://nightly.mealie.io/documentation/getting-started/migrating-to-mealie-v1/

I would spin up a different instance along side your original v0.5.6 version, export your old data, and then import it into the new version using the instructions above.

Also, I would just use SQLite if it's an option, I'm not sure why they would use Postgres as the default, it's the opposite of what is recommended on the site.

That should resolve all the other problems you're seeing in the logs as well.

[yottabit42]

Thanks! I just found that migration also supports the prior Mealie major version, which I would not have assumed unless I poked around in the dropdown box and saw Mealie listed. TrueCharts also gave me a pointer there.

The import was successful! Although I don't have the recipe images, but somehow lost those in the import of the backup into the old version, too. Is there a way to automatically pull new recipe images? I couldn't find an option.

As for the database, unfortunately it's out of my control. TrueCharts standardized on Postgres as I guess they use it for a lot of the other apps as well.

Thanks for your help! My family loves the app!

[hay-kot]

Thanks for the kind words!

You likely didn't lose your images, the frontend and backend container are supposed to share a volume mount, if that's not configured correctly your images won't show up.

If you go the setting pages for Mealie, there's a button to validate your docker volumes, can you check if that is valid?

[yottabit42]

The Docker check was successful. I think I lost the images from the backup archive at some point in the past while troubleshooting.

I cleared the app and imported a known good database, and everything is present now, including images.

Thanks again! I'm looking forward to trying out the new UI and features!

[CamelT0E]

I also have a little problem. I would like to update from version 0.5.6. I'm using postgresql. i don't understand the instructions: https://nightly.mealie.io/documentation/getting-started/migrating-to-mealie-v1/

I can't find an export button in version 0.5.6. did I miss or misunderstand something? Old version has no export in the admin section.

Please help me

[hay-kot]

This is at /admin/dashboard in v0.5.x

[CamelT0E]

many many many thanks ;)
you made my day

we love your project (run on Synology NAS)