truecharts / public

Community Helm Chart Repository
https://truecharts.org
GNU Affero General Public License v3.0
1.14k stars 617 forks source link

Mealie Upgrade Fails #3970

Closed yottabit42 closed 2 years ago

yottabit42 commented 2 years ago

App Name

Mealie

SCALE Version

22.02.4

App Version

0.5.6_9.0.3 -> 1.0.0beta_10.0.4

Application Events

2022-09-30 9:45:15
Startup probe failed: Get "http://172.17.0.24:80/": dial tcp 172.17.0.24:80: connect: connection refused
2022-09-30 9:45:17
Back-off restarting failed container
2022-09-30 9:45:06
Started container api
2022-09-30 9:45:06
Created container api
2022-09-30 9:45:03
Container image "tccr.io/truecharts/mealie-api:v1.0.0beta@sha256:4dad1521060d896c2e4782f57ca48007e831b2df497746637eb8f2d6a4a23c90" already present on machine
2022-09-30 9:45:03
Started container mealie-9-0-3
2022-09-30 9:45:03
Created container mealie-9-0-3
2022-09-30 9:45:01
Container image "tccr.io/truecharts/mealie-frontend:v1.0.0beta@sha256:be031b8c1c6a1698f18e7750a1482ca85f6c311bbb594cf8da46d0e206b0729a" already present on machine
2022-09-30 9:44:46
Started container mealie-9-0-3-postgresql
2022-09-30 9:44:46
Created container mealie-9-0-3-postgresql
2022-09-30 9:33:13
Applied LoadBalancer DaemonSet ix-mealie-9-0-3/svclb-mealie-9-0-3
2022-09-30 9:44:44
Started container prepare
2022-09-30 9:44:44
Created container prepare
2022-09-30 9:44:42
Container image "tccr.io/truecharts/postgresql:14.5.0@sha256:aa74c071ccea630972060d1291cacf4b58a8a549fca9d0d298ca115d5f8a536e" already present on machine
2022-09-30 9:44:42
Started container prepare
2022-09-30 9:44:42
Created container prepare
2022-09-30 9:44:38
Container image "tccr.io/truecharts/multi-init:v0.0.1@sha256:5c1367f74527c7cdf02d034d721d0d7ac15ac08f5ab0b7f9106152c05e053618" already present on machine
2022-09-30 9:44:38
Add eth0 [172.17.0.24/16] from ix-net
2022-09-30 9:44:37
Container image "tccr.io/truecharts/multi-init:v0.0.1@sha256:5c1367f74527c7cdf02d034d721d0d7ac15ac08f5ab0b7f9106152c05e053618" already present on machine
2022-09-30 9:44:37
Add eth0 [172.17.0.23/16] from ix-net
Successfully assigned ix-mealie-9-0-3/mealie-9-0-3-5bf8f6658-s4jpp to ix-truenas
2022-09-30 9:44:30
Created pod: mealie-9-0-3-5bf8f6658-s4jpp
2022-09-30 9:44:30
Scaled up replica set mealie-9-0-3-5bf8f6658 to 1
Successfully assigned ix-mealie-9-0-3/mealie-9-0-3-postgresql-0 to ix-truenas
2022-09-30 9:33:32
create Pod mealie-9-0-3-postgresql-0 in StatefulSet mealie-9-0-3-postgresql successful
2022-09-30 9:44:20
Successfully provisioned volume pvc-d636f427-b0f6-480c-9077-837f7d9381aa
2022-09-30 9:44:18
Stopping container mealie-9-0-3-postgresql
2022-09-30 9:44:18
Stopping container mealie-9-0-3
2022-09-30 9:44:18
delete Pod mealie-9-0-3-postgresql-0 in StatefulSet mealie-9-0-3-postgresql successful
2022-09-30 9:44:18
Deleted pod: mealie-9-0-3-86c5ddddfd-9b7k8
2022-09-30 9:44:18
Scaled down replica set mealie-9-0-3-86c5ddddfd to 0
2022-09-30 9:44:18
waiting for a volume to be created, either by external provisioner "zfs.csi.openebs.io" or manually created by system administrator
2022-09-30 9:44:18
External provisioner is provisioning volume for claim "ix-mealie-9-0-3/mealie-9-0-3-data"
2022-09-30 9:36:49
Startup probe failed: dial tcp 172.17.0.19:80: connect: connection refused
2022-09-30 9:36:37
Started container mealie-9-0-3
2022-09-30 9:36:36
Created container mealie-9-0-3
2022-09-30 9:36:25
Container image "tccr.io/truecharts/mealie:v0.5.6@sha256:e54cf37a3da0d6853197c200ddbb858de03e0f12752c1f862856376b0733af0a" already present on machine
2022-09-30 9:36:13
Started container prepare
2022-09-30 9:36:13
Created container prepare
2022-09-30 9:36:09
Started container mealie-9-0-3-postgresql
2022-09-30 9:36:08
Created container mealie-9-0-3-postgresql
2022-09-30 9:36:03
Container image "tccr.io/truecharts/multi-init:v0.0.1@sha256:4c2caebee117b055f379377fd0fd306f2ee0e6697fd47d9364073e0e9e6f2e02" already present on machine
2022-09-30 9:36:03
Add eth0 [172.17.0.19/16] from ix-net
2022-09-30 9:35:57
Container image "tccr.io/truecharts/postgresql:14.5.0@sha256:efd384c1a58943ddf25b780f616a77717b372649bd6baede90b9e713cbdc44db" already present on machine
2022-09-30 9:35:53
Started container prepare
2022-09-30 9:35:52
Created container prepare
2022-09-30 9:35:42
Container image "tccr.io/truecharts/multi-init:v0.0.1@sha256:4c2caebee117b055f379377fd0fd306f2ee0e6697fd47d9364073e0e9e6f2e02" already present on machine
2022-09-30 9:35:42
Add eth0 [172.17.0.16/16] from ix-net
2022-09-30 9:34:47
MountVolume.MountDevice failed for volume "pvc-e120721c-07e1-4e89-83e4-75dd86ede408" : kubernetes.io/csi: attacher.MountDevice failed to create newCsiDriverClient: driver name zfs.csi.openebs.io not found in the list of registered CSI drivers
Successfully assigned ix-mealie-9-0-3/mealie-9-0-3-86c5ddddfd-9b7k8 to ix-truenas
2022-09-30 9:33:39
MountVolume.MountDevice failed for volume "pvc-e41289fe-4090-4a48-915d-3fea061b43ae" : kubernetes.io/csi: attacher.MountDevice failed to create newCsiDriverClient: driver name zfs.csi.openebs.io not found in the list of registered CSI drivers
2022-09-30 9:34:03
Started container lb-tcp-10018
2022-09-30 9:34:03
Created container lb-tcp-10018
2022-09-30 9:33:48
Container image "rancher/klipper-lb:v0.3.4" already present on machine
2022-09-30 9:33:48
Add eth0 [172.17.0.7/16] from ix-net
2022-09-30 9:33:22
MountVolume.MountDevice failed for volume "pvc-e41289fe-4090-4a48-915d-3fea061b43ae" : kubernetes.io/csi: attacher.MountDevice failed to create newCsiDriverClient: driver name zfs.csi.openebs.io not found in the list of registered CSI drivers
2022-09-30 9:33:19
network is not ready: container runtime network not ready: NetworkReady=false reason:NetworkPluginNotReady message:docker: network plugin is not ready: cni config uninitialized
2022-09-30 9:33:24
MountVolume.MountDevice failed for volume "pvc-e120721c-07e1-4e89-83e4-75dd86ede408" : kubernetes.io/csi: attacher.MountDevice failed to create newCsiDriverClient: driver name zfs.csi.openebs.io not found in the list of registered CSI drivers
2022-09-30 9:33:19
network is not ready: container runtime network not ready: NetworkReady=false reason:NetworkPluginNotReady message:docker: network plugin is not ready: cni config uninitialized
Successfully assigned ix-mealie-9-0-3/svclb-mealie-9-0-3-kxpxt to ix-truenas
2022-09-30 9:33:34
Created pod: svclb-mealie-9-0-3-kxpxt
2022-09-30 9:33:18
network is not ready: container runtime network not ready: NetworkReady=false reason:NetworkPluginNotReady message:docker: network plugin is not ready: cni config uninitialized
2022-09-30 9:33:26
Marking for deletion Pod ix-mealie-9-0-3/mealie-9-0-3-86c5ddddfd-qcnbq
2022-09-30 9:33:26
Marking for deletion Pod ix-mealie-9-0-3/svclb-mealie-9-0-3-nfz5k
Successfully assigned ix-mealie-9-0-3/mealie-9-0-3-postgresql-0 to ix-truenas
2022-09-30 9:33:26
Marking for deletion Pod ix-mealie-9-0-3/mealie-9-0-3-postgresql-0
2022-09-30 9:33:26
Cancelling deletion of Pod ix-mealie-9-0-3/mealie-9-0-3-86c5ddddfd-qcnbq
2022-09-30 9:33:26
Cancelling deletion of Pod ix-mealie-9-0-3/svclb-mealie-9-0-3-nfz5k
2022-09-30 9:33:26
Cancelling deletion of Pod ix-mealie-9-0-3/mealie-9-0-3-postgresql-0
0/1 nodes are available: 1 node(s) had taint {ix-svc-start: }, that the pod didn't tolerate.
2022-09-30 9:33:26
Created pod: mealie-9-0-3-86c5ddddfd-9b7k8
2022-09-30 9:33:17
Node didn't have enough resource: nvidia.com/gpu, requested: 0, used: 1, capacity: 0
2022-09-29 22:20:38
Applied LoadBalancer DaemonSet ix-mealie-9-0-3/svclb-mealie-9-0-3
2022-09-30 9:21:34
Started container mealie-9-0-3
2022-09-30 9:21:34
Created container mealie-9-0-3
2022-09-30 9:21:32
Container image "tccr.io/truecharts/mealie:v0.5.6@sha256:e54cf37a3da0d6853197c200ddbb858de03e0f12752c1f862856376b0733af0a" already present on machine
2022-09-30 9:21:19
Started container mealie-9-0-3-postgresql
2022-09-30 9:21:19
Created container mealie-9-0-3-postgresql
2022-09-30 9:21:16
Container image "tccr.io/truecharts/postgresql:14.5.0@sha256:efd384c1a58943ddf25b780f616a77717b372649bd6baede90b9e713cbdc44db" already present on machine
2022-09-30 9:21:15
Started container prepare
2022-09-30 9:21:15
Created container prepare
2022-09-30 9:21:10
Container image "tccr.io/truecharts/multi-init:v0.0.1@sha256:4c2caebee117b055f379377fd0fd306f2ee0e6697fd47d9364073e0e9e6f2e02" already present on machine
2022-09-30 9:21:10
Add eth0 [172.17.0.141/16] from ix-net
2022-09-30 9:21:08
Started container prepare
2022-09-30 9:21:08
Created container prepare
Successfully assigned ix-mealie-9-0-3/mealie-9-0-3-postgresql-0 to ix-truenas
2022-09-29 22:20:38
create Pod mealie-9-0-3-postgresql-0 in StatefulSet mealie-9-0-3-postgresql successful
2022-09-30 9:21:03
Container image "tccr.io/truecharts/multi-init:v0.0.1@sha256:4c2caebee117b055f379377fd0fd306f2ee0e6697fd47d9364073e0e9e6f2e02" already present on machine
2022-09-30 9:21:03
Add eth0 [172.17.0.140/16] from ix-net
2022-09-30 9:21:03
Started container prepare
2022-09-30 9:21:02
Created container prepare
2022-09-30 9:20:56
Container image "tccr.io/truecharts/multi-init:v0.0.1@sha256:4c2caebee117b055f379377fd0fd306f2ee0e6697fd47d9364073e0e9e6f2e02" already present on machine
2022-09-30 9:20:56
Add eth0 [172.17.0.139/16] from ix-net
Successfully assigned ix-mealie-9-0-3/mealie-9-0-3-86c5ddddfd-qcnbq to ix-truenas
0/1 nodes are available: 1 pod has unbound immediate PersistentVolumeClaims.
2022-09-30 9:20:53
Successfully provisioned volume pvc-e120721c-07e1-4e89-83e4-75dd86ede408
0/1 nodes are available: 1 pod has unbound immediate PersistentVolumeClaims.
2022-09-30 9:20:51
Created pod: mealie-9-0-3-86c5ddddfd-qcnbq
0/1 nodes are available: 1 pod has unbound immediate PersistentVolumeClaims.
2022-09-30 9:20:51
Created pod: mealie-9-0-3-86c5ddddfd-mc9nj
Successfully assigned ix-mealie-9-0-3/mealie-9-0-3-postgresql-0 to ix-truenas
0/1 nodes are available: 1 pod has unbound immediate PersistentVolumeClaims.
2022-09-30 9:20:51
Created pod: mealie-9-0-3-86c5ddddfd-crlp9
2022-09-29 22:20:38
Scaled up replica set mealie-9-0-3-86c5ddddfd to 1
2022-09-30 9:20:51
waiting for a volume to be created, either by external provisioner "zfs.csi.openebs.io" or manually created by system administrator
2022-09-30 9:20:51
External provisioner is provisioning volume for claim "ix-mealie-9-0-3/mealie-9-0-3-config"
2022-09-30 9:20:33
Stopping container mealie-9-0-3-postgresql
2022-09-29 22:27:57
delete Pod mealie-9-0-3-postgresql-0 in StatefulSet mealie-9-0-3-postgresql successful
2022-09-30 9:20:33
Deleted pod: mealie-9-0-3-5bf8f6658-9b5wz
2022-09-29 22:32:42
Scaled down replica set mealie-9-0-3-5bf8f6658 to 0
2022-09-30 9:11:01
Startup probe failed: Get "http://172.17.0.138:80/": dial tcp 172.17.0.138:80: connect: connection refused
2022-09-30 9:11:05
Back-off restarting failed container
2022-09-30 9:10:54
Started container api
2022-09-30 9:10:54
Created container api
2022-09-30 9:10:51
Container image "tccr.io/truecharts/mealie-api:v1.0.0beta@sha256:4dad1521060d896c2e4782f57ca48007e831b2df497746637eb8f2d6a4a23c90" already present on machine
2022-09-30 9:10:51
Started container mealie-9-0-3
2022-09-30 9:10:51
Created container mealie-9-0-3
2022-09-30 9:10:48
Container image "tccr.io/truecharts/mealie-frontend:v1.0.0beta@sha256:be031b8c1c6a1698f18e7750a1482ca85f6c311bbb594cf8da46d0e206b0729a" already present on machine
2022-09-30 9:10:31
Started container mealie-9-0-3-postgresql
2022-09-30 9:10:31
Created container mealie-9-0-3-postgresql
2022-09-30 9:10:29
Started container prepare
2022-09-30 9:10:28
Container image "tccr.io/truecharts/postgresql:14.5.0@sha256:aa74c071ccea630972060d1291cacf4b58a8a549fca9d0d298ca115d5f8a536e" already present on machine
2022-09-30 9:10:28
Created container prepare
2022-09-30 9:10:28
Started container prepare
2022-09-30 9:10:28
Created container prepare
2022-09-30 9:10:24
Container image "tccr.io/truecharts/multi-init:v0.0.1@sha256:5c1367f74527c7cdf02d034d721d0d7ac15ac08f5ab0b7f9106152c05e053618" already present on machine
2022-09-30 9:10:24
Add eth0 [172.17.0.138/16] from ix-net
2022-09-30 9:10:23
Container image "tccr.io/truecharts/multi-init:v0.0.1@sha256:5c1367f74527c7cdf02d034d721d0d7ac15ac08f5ab0b7f9106152c05e053618" already present on machine
2022-09-30 9:10:23
Add eth0 [172.17.0.137/16] from ix-net
Successfully assigned ix-mealie-9-0-3/mealie-9-0-3-5bf8f6658-9b5wz to ix-truenas
2022-09-30 9:10:16
Created pod: mealie-9-0-3-5bf8f6658-9b5wz
2022-09-29 22:28:09
Scaled up replica set mealie-9-0-3-5bf8f6658 to 1
Successfully assigned ix-mealie-9-0-3/mealie-9-0-3-postgresql-0 to ix-truenas
2022-09-30 9:10:05
Successfully provisioned volume pvc-f3d86deb-ebe4-40e5-b8c9-af9d9fb32c5c
2022-09-30 9:10:04
Stopping container mealie-9-0-3
2022-09-30 9:10:04
Deleted pod: mealie-9-0-3-86c5ddddfd-2nzsp
2022-09-29 22:27:57
Scaled down replica set mealie-9-0-3-86c5ddddfd to 0
2022-09-30 9:10:04
Stopping container mealie-9-0-3-postgresql
2022-09-30 9:10:03
waiting for a volume to be created, either by external provisioner "zfs.csi.openebs.io" or manually created by system administrator
2022-09-30 9:10:04
External provisioner is provisioning volume for claim "ix-mealie-9-0-3/mealie-9-0-3-data"
2022-09-30 9:03:44
Started container mealie-9-0-3
2022-09-30 9:03:44
Created container mealie-9-0-3
2022-09-30 9:03:42
Container image "tccr.io/truecharts/mealie:v0.5.6@sha256:e54cf37a3da0d6853197c200ddbb858de03e0f12752c1f862856376b0733af0a" already present on machine
2022-09-30 9:03:31
Started container mealie-9-0-3-postgresql
2022-09-30 9:03:31
Created container mealie-9-0-3-postgresql
2022-09-30 9:03:28
Container image "tccr.io/truecharts/postgresql:14.5.0@sha256:efd384c1a58943ddf25b780f616a77717b372649bd6baede90b9e713cbdc44db" already present on machine
2022-09-30 9:03:27
Started container prepare
2022-09-30 9:03:27
Created container prepare
2022-09-30 9:03:27
Started container prepare
2022-09-30 9:03:27
Created container prepare
2022-09-30 9:03:23
Container image "tccr.io/truecharts/multi-init:v0.0.1@sha256:4c2caebee117b055f379377fd0fd306f2ee0e6697fd47d9364073e0e9e6f2e02" already present on machine
2022-09-30 9:03:23
Add eth0 [172.17.0.136/16] from ix-net
2022-09-30 9:03:22
Container image "tccr.io/truecharts/multi-init:v0.0.1@sha256:4c2caebee117b055f379377fd0fd306f2ee0e6697fd47d9364073e0e9e6f2e02" already present on machine
2022-09-30 9:03:22
Add eth0 [172.17.0.135/16] from ix-net
Successfully assigned ix-mealie-9-0-3/mealie-9-0-3-postgresql-0 to ix-truenas
Successfully assigned ix-mealie-9-0-3/mealie-9-0-3-86c5ddddfd-2nzsp to ix-truenas
2022-09-30 9:03:16
Created pod: mealie-9-0-3-86c5ddddfd-2nzsp
2022-09-30 9:02:53
Stopping container mealie-9-0-3-postgresql
2022-09-30 9:02:53
Stopping container mealie-9-0-3
2022-09-30 9:02:53
Deleted pod: mealie-9-0-3-86c5ddddfd-dgn7c

Application Logs

Application Name: mealie-9-0-3
Pod Name: mealie-9-0-3-postgresql-0
Container Name: mealie-9-0-3-postgresql

2022-09-30 14:44:46.749452+00:00postgresql 14:44:46.74 
2022-09-30 14:44:46.750718+00:00postgresql 14:44:46.75 Welcome to the Bitnami postgresql container
2022-09-30 14:44:46.752003+00:00postgresql 14:44:46.75 Subscribe to project updates by watching https://github.com/bitnami/containers
2022-09-30 14:44:46.753222+00:00postgresql 14:44:46.75 Submit issues and feature requests at https://github.com/bitnami/containers/issues
2022-09-30 14:44:46.754529+00:00postgresql 14:44:46.75 
2022-09-30 14:44:46.768869+00:00postgresql 14:44:46.76 INFO  ==> ** Starting PostgreSQL setup **
2022-09-30 14:44:46.786663+00:00postgresql 14:44:46.78 INFO  ==> Validating settings in POSTGRESQL_* env vars..
2022-09-30 14:44:46.791962+00:00postgresql 14:44:46.79 INFO  ==> Cleaning stale /bitnami/postgresql/data/postmaster.pid file
2022-09-30 14:44:46.795094+00:00postgresql 14:44:46.79 INFO  ==> Loading custom pre-init scripts...
2022-09-30 14:44:46.800893+00:00postgresql 14:44:46.80 INFO  ==> Initializing PostgreSQL database...
2022-09-30 14:44:46.815731+00:00postgresql 14:44:46.81 INFO  ==> pg_hba.conf file not detected. Generating it...
2022-09-30 14:44:46.817263+00:00postgresql 14:44:46.81 INFO  ==> Generating local authentication configuration
2022-09-30 14:44:46.832136+00:00postgresql 14:44:46.83 INFO  ==> Deploying PostgreSQL with persisted data...
2022-09-30 14:44:46.858052+00:00postgresql 14:44:46.85 INFO  ==> Configuring replication parameters
2022-09-30 14:44:46.882211+00:00postgresql 14:44:46.88 INFO  ==> Configuring fsync
2022-09-30 14:44:46.888489+00:00postgresql 14:44:46.88 INFO  ==> Configuring synchronous_replication
2022-09-30 14:44:46.903075+00:00postgresql 14:44:46.90 INFO  ==> Loading custom scripts...
2022-09-30 14:44:46.907123+00:00postgresql 14:44:46.90 INFO  ==> Enabling remote connections
2022-09-30 14:44:46.915577+00:002022-09-30T14:44:46.915577172Z
2022-09-30 14:44:46.915591+00:00postgresql 14:44:46.91 INFO  ==> ** PostgreSQL setup finished! **
2022-09-30 14:44:46.933582+00:00postgresql 14:44:46.93 INFO  ==> ** Starting PostgreSQL **
2022-09-30 14:44:46.967354+00:002022-09-30 14:44:46.967 GMT [1] LOG:  pgaudit extension initialized
2022-09-30 14:44:46.972168+00:002022-09-30 14:44:46.971 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:44:46.972682+00:002022-09-30 14:44:46.972 GMT [1] LOG:  listening on IPv4 address "0.0.0.0", port 5432
2022-09-30 14:44:46.972728+00:002022-09-30 14:44:46.972 GMT [1] LOG:  listening on IPv6 address "::", port 5432
2022-09-30 14:44:46.972772+00:002022-09-30 14:44:46.972 GMT [1] LOG:  listening on Unix socket "/tmp/.s.PGSQL.5432"
2022-09-30 14:44:46.977104+00:002022-09-30 14:44:46.976 GMT [87] LOG:  database system was interrupted; last known up at 2022-09-30 14:41:21 GMT
2022-09-30 14:44:47.003187+00:002022-09-30 14:44:47.002 GMT [87] LOG:  database system was not properly shut down; automatic recovery in progress
2022-09-30 14:44:47.004281+00:002022-09-30 14:44:47.004 GMT [87] LOG:  redo starts at 0/198E498
2022-09-30 14:44:47.004328+00:002022-09-30 14:44:47.004 GMT [87] LOG:  invalid record length at 0/198E580: wanted 24, got 0
2022-09-30 14:44:47.004347+00:002022-09-30 14:44:47.004 GMT [87] LOG:  redo done at 0/198E548 system usage: CPU: user: 0.00 s, system: 0.00 s, elapsed: 0.00 s
2022-09-30 14:44:47.010248+00:002022-09-30 14:44:47.010 GMT [1] LOG:  database system is ready to accept connections
2022-09-30 14:45:09.995508+00:002022-09-30 14:45:09.995 GMT [113] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:45:09.995576+00:002022-09-30 14:45:09.995 GMT [113] STATEMENT:  
2022-09-30 14:45:09.995601+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:45:09.995612+00:00cookbook_id UUID, 
2022-09-30 14:45:09.995618+00:00tag_id UUID, 
2022-09-30 14:45:09.995625+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:45:09.995630+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:45:09.995643+00:00)
2022-09-30 14:45:09.995649+00:002022-09-30T14:45:09.995649106Z
2022-09-30 14:45:09.995654+00:002022-09-30T14:45:09.995654926Z
2022-09-30 14:45:16.404023+00:002022-09-30 14:45:16.403 GMT [133] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:45:16.404097+00:002022-09-30 14:45:16.403 GMT [133] STATEMENT:  
2022-09-30 14:45:16.404119+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:45:16.404126+00:00cookbook_id UUID, 
2022-09-30 14:45:16.404131+00:00tag_id UUID, 
2022-09-30 14:45:16.404137+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:45:16.404144+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:45:16.404156+00:00)
2022-09-30 14:45:16.404163+00:002022-09-30T14:45:16.404163019Z
2022-09-30 14:45:16.404168+00:002022-09-30T14:45:16.404168785Z
2022-09-30 14:45:40.227372+00:002022-09-30 14:45:40.227 GMT [185] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:45:40.227449+00:002022-09-30 14:45:40.227 GMT [185] STATEMENT:  
2022-09-30 14:45:40.227472+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:45:40.227479+00:00cookbook_id UUID, 
2022-09-30 14:45:40.227484+00:00tag_id UUID, 
2022-09-30 14:45:40.227490+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:45:40.227502+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:45:40.227509+00:00)
2022-09-30 14:45:40.227527+00:002022-09-30T14:45:40.227527796Z
2022-09-30 14:45:40.227533+00:002022-09-30T14:45:40.227533506Z
2022-09-30 14:46:09.395776+00:002022-09-30 14:46:09.395 GMT [222] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:46:09.395949+00:002022-09-30 14:46:09.395 GMT [222] STATEMENT:  
2022-09-30 14:46:09.395973+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:46:09.395990+00:00cookbook_id UUID, 
2022-09-30 14:46:09.396007+00:00tag_id UUID, 
2022-09-30 14:46:09.396022+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:46:09.396052+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:46:09.396069+00:00)
2022-09-30 14:46:09.396084+00:002022-09-30T14:46:09.396084467Z
2022-09-30 14:46:09.396099+00:002022-09-30T14:46:09.396099497Z
2022-09-30 14:46:58.133195+00:002022-09-30 14:46:58.133 GMT [307] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:46:58.133300+00:002022-09-30 14:46:58.133 GMT [307] STATEMENT:  
2022-09-30 14:46:58.133331+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:46:58.133367+00:00cookbook_id UUID, 
2022-09-30 14:46:58.133382+00:00tag_id UUID, 
2022-09-30 14:46:58.133398+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:46:58.133445+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:46:58.133463+00:00)
2022-09-30 14:46:58.133488+00:002022-09-30T14:46:58.133488302Z
2022-09-30 14:46:58.133525+00:002022-09-30T14:46:58.133525137Z
2022-09-30 14:48:31.352787+00:002022-09-30 14:48:31.352 GMT [473] ERROR:  relation "cookbooks" does not exist
2022-09-30 14:48:31.352873+00:002022-09-30 14:48:31.352 GMT [473] STATEMENT:  
2022-09-30 14:48:31.352882+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:48:31.352889+00:00cookbook_id UUID, 
2022-09-30 14:48:31.352894+00:00tag_id UUID, 
2022-09-30 14:48:31.352900+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:48:31.352912+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:48:31.352918+00:00)
2022-09-30 14:48:31.352923+00:002022-09-30T14:48:31.352923843Z
2022-09-30 14:48:31.352929+00:002022-09-30T14:48:31.352929409Z

- - -

Application Name: mealie-9-0-3
Pod Name: mealie-9-0-3-postgresql-0
Container Name: prepare

2022-09-30 14:44:42.208245+00:00Automatically correcting permissions...

- - -

Application Name: mealie-9-0-3
Pod Name: mealie-9-0-3-5bf8f6658-s4jpp
Container Name: api

2022-09-30 14:51:15.730866+00:00Production
2022-09-30 14:51:15.758277+00:002022-09-30T14:51:15.758277222Z
2022-09-30 14:51:15.758304+00:00User uid:    568
2022-09-30 14:51:15.758310+00:00User gid:    568
2022-09-30 14:51:15.758316+00:002022-09-30T14:51:15.758316892Z
2022-09-30 14:51:18.224602+00:00INFO: 30-Sep-22 09:51:18    Database connection established.
2022-09-30 14:51:18.235600+00:00INFO: 30-Sep-22 09:51:18    Context impl PostgresqlImpl.
2022-09-30 14:51:18.235625+00:00INFO: 30-Sep-22 09:51:18    Will assume transactional DDL.
2022-09-30 14:51:18.247149+00:00INFO: 30-Sep-22 09:51:18    Migration needed. Performing migration...
2022-09-30 14:51:18.258396+00:00INFO: 30-Sep-22 09:51:18    Context impl PostgresqlImpl.
2022-09-30 14:51:18.258422+00:00INFO: 30-Sep-22 09:51:18    Will assume transactional DDL.
2022-09-30 14:51:18.270680+00:00INFO: 30-Sep-22 09:51:18    Running upgrade  -> 6b0f5f32d602, Initial tables
2022-09-30 14:51:18.282859+00:00INFO: 30-Sep-22 09:51:18    Running upgrade 6b0f5f32d602 -> 263dd6707191, convert quantity from integer to float
2022-09-30 14:51:18.290896+00:00INFO: 30-Sep-22 09:51:18    Running upgrade 263dd6707191 -> f1a2dbee5fe9, Add original_text column to recipes_ingredients
2022-09-30 14:51:18.292345+00:00INFO: 30-Sep-22 09:51:18    Running upgrade f1a2dbee5fe9 -> 59eb59135381, add tags to cookbooks
2022-09-30 14:51:18.294834+00:00Traceback (most recent call last):
2022-09-30 14:51:18.294854+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 1819, in _execute_context
2022-09-30 14:51:18.295459+00:00self.dialect.do_execute(
2022-09-30 14:51:18.295476+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/default.py", line 732, in do_execute
2022-09-30 14:51:18.295853+00:00cursor.execute(statement, parameters)
2022-09-30 14:51:18.295878+00:00psycopg2.errors.UndefinedTable: relation "cookbooks" does not exist
2022-09-30 14:51:18.295885+00:002022-09-30T14:51:18.295885796Z
2022-09-30 14:51:18.295891+00:002022-09-30T14:51:18.295891586Z
2022-09-30 14:51:18.295897+00:00The above exception was the direct cause of the following exception:
2022-09-30 14:51:18.295903+00:002022-09-30T14:51:18.295903087Z
2022-09-30 14:51:18.295917+00:00Traceback (most recent call last):
2022-09-30 14:51:18.295927+00:00File "/app/mealie/db/init_db.py", line 108, in <module>
2022-09-30 14:51:18.295966+00:00main()
2022-09-30 14:51:18.295992+00:00File "/app/mealie/db/init_db.py", line 94, in main
2022-09-30 14:51:18.296075+00:00command.upgrade(alembic_cfg, "head")
2022-09-30 14:51:18.296107+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/command.py", line 322, in upgrade
2022-09-30 14:51:18.296352+00:00script.run_env()
2022-09-30 14:51:18.296371+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/script/base.py", line 569, in run_env
2022-09-30 14:51:18.296716+00:00util.load_python_file(self.dir, "env.py")
2022-09-30 14:51:18.296737+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/util/pyfiles.py", line 94, in load_python_file
2022-09-30 14:51:18.296829+00:00module = load_module_py(module_id, path)
2022-09-30 14:51:18.296847+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/util/pyfiles.py", line 110, in load_module_py
2022-09-30 14:51:18.296978+00:00spec.loader.exec_module(module)  # type: ignore
2022-09-30 14:51:18.296997+00:00File "<frozen importlib._bootstrap_external>", line 883, in exec_module
2022-09-30 14:51:18.297198+00:00File "<frozen importlib._bootstrap>", line 241, in _call_with_frames_removed
2022-09-30 14:51:18.297311+00:00File "/app/alembic/env.py", line 77, in <module>
2022-09-30 14:51:18.297419+00:00run_migrations_online()
2022-09-30 14:51:18.297436+00:00File "/app/alembic/env.py", line 71, in run_migrations_online
2022-09-30 14:51:18.297951+00:00context.run_migrations()
2022-09-30 14:51:18.297986+00:00File "<string>", line 8, in run_migrations
2022-09-30 14:51:18.298013+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/runtime/environment.py", line 853, in run_migrations
2022-09-30 14:51:18.298129+00:00self.get_context().run_migrations(**kw)
2022-09-30 14:51:18.298146+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/runtime/migration.py", line 623, in run_migrations
2022-09-30 14:51:18.298539+00:00step.migration_fn(**kw)
2022-09-30 14:51:18.298558+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:51:18.298642+00:00op.create_table(
2022-09-30 14:51:18.298665+00:00File "<string>", line 8, in create_table
2022-09-30 14:51:18.298765+00:00File "<string>", line 3, in create_table
2022-09-30 14:51:18.298876+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/operations/ops.py", line 1254, in create_table
2022-09-30 14:51:18.308876+00:00return operations.invoke(op)
2022-09-30 14:51:18.308943+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/operations/base.py", line 394, in invoke
2022-09-30 14:51:18.308983+00:00return fn(self, operation)
2022-09-30 14:51:18.308995+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/operations/toimpl.py", line 114, in create_table
2022-09-30 14:51:18.309008+00:00operations.impl.create_table(table)
2022-09-30 14:51:18.309030+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/ddl/impl.py", line 354, in create_table
2022-09-30 14:51:18.309040+00:00self._exec(schema.CreateTable(table))
2022-09-30 14:51:18.309050+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/alembic/ddl/impl.py", line 195, in _exec
2022-09-30 14:51:18.309061+00:00return conn.execute(construct, multiparams)
2022-09-30 14:51:18.309081+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 1306, in execute
2022-09-30 14:51:18.309092+00:00return meth(self, multiparams, params, _EMPTY_EXECUTION_OPTS)
2022-09-30 14:51:18.309098+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/sql/ddl.py", line 80, in _execute_on_connection
2022-09-30 14:51:18.309110+00:00return connection._execute_ddl(
2022-09-30 14:51:18.309117+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 1398, in _execute_ddl
2022-09-30 14:51:18.309123+00:00ret = self._execute_context(
2022-09-30 14:51:18.309128+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 1862, in _execute_context
2022-09-30 14:51:18.309139+00:00self._handle_dbapi_exception(
2022-09-30 14:51:18.309145+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 2043, in _handle_dbapi_exception
2022-09-30 14:51:18.309151+00:00util.raise_(
2022-09-30 14:51:18.309161+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/util/compat.py", line 208, in raise_
2022-09-30 14:51:18.309167+00:00raise exception
2022-09-30 14:51:18.309172+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/base.py", line 1819, in _execute_context
2022-09-30 14:51:18.309178+00:00self.dialect.do_execute(
2022-09-30 14:51:18.309190+00:00File "/opt/pysetup/.venv/lib/python3.10/site-packages/sqlalchemy/engine/default.py", line 732, in do_execute
2022-09-30 14:51:18.309196+00:00cursor.execute(statement, parameters)
2022-09-30 14:51:18.309202+00:00sqlalchemy.exc.ProgrammingError: (psycopg2.errors.UndefinedTable) relation "cookbooks" does not exist
2022-09-30 14:51:18.309213+00:002022-09-30T14:51:18.309213625Z
2022-09-30 14:51:18.309219+00:00[SQL: 
2022-09-30 14:51:18.309224+00:00CREATE TABLE cookbooks_to_tags (
2022-09-30 14:51:18.309230+00:00cookbook_id UUID, 
2022-09-30 14:51:18.309236+00:00tag_id UUID, 
2022-09-30 14:51:18.309241+00:00FOREIGN KEY(cookbook_id) REFERENCES cookbooks (id), 
2022-09-30 14:51:18.309252+00:00FOREIGN KEY(tag_id) REFERENCES tags (id)
2022-09-30 14:51:18.309259+00:00)
2022-09-30 14:51:18.309265+00:002022-09-30T14:51:18.309265397Z
2022-09-30 14:51:18.309270+00:00]
2022-09-30 14:51:18.309276+00:00(Background on this error at: https://sqlalche.me/e/14/f405)

- - -

Application Name: mealie-9-0-3
Pod Name: mealie-9-0-3-5bf8f6658-s4jpp
Container Name: mealie-9-0-3

2022-09-30 14:50:23.668851+00:00{"level":"info","ts":1664549423.668503,"msg":"using provided configuration","config_file":"/app/Caddyfile","config_adapter":""}
2022-09-30 14:50:23.671137+00:00{"level":"warn","ts":1664549423.670972,"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:50:23.671637+00:00{"level":"warn","ts":1664549423.671486,"logger":"admin","msg":"admin endpoint disabled"}
2022-09-30 14:50:23.672200+00:00{"level":"warn","ts":1664549423.6720386,"logger":"http","msg":"automatic HTTPS is completely disabled for server","server_name":"srv0"}
2022-09-30 14:50:23.672642+00:00{"level":"info","ts":1664549423.6724749,"logger":"tls.cache.maintenance","msg":"started background certificate maintenance","cache":"0xc00042e9a0"}
2022-09-30 14:50:23.673441+00:00{"level":"info","ts":1664549423.6732707,"logger":"tls","msg":"cleaning storage unit","description":"FileStorage:/root/.local/share/caddy"}
2022-09-30 14:50:23.673491+00:00{"level":"info","ts":1664549423.673355,"logger":"tls","msg":"finished cleaning storage units"}
2022-09-30 14:50:23.674395+00:00{"level":"info","ts":1664549423.6742344,"msg":"autosaved config (load with --resume flag)","file":"/root/.config/caddy/autosave.json"}
2022-09-30 14:50:23.674464+00:00{"level":"info","ts":1664549423.674257,"msg":"serving initial configuration"}
2022-09-30 14:50:23.674734+00:00Successfully started Caddy (pid=20) - Caddy is running in the background
2022-09-30 14:50:24.112538+00:00yarn run v1.22.19
2022-09-30 14:50:24.149575+00:00$ nuxt start -p 3001
2022-09-30 14:50:24.717150+00:00ℹ [HPM] Proxy created: api  -> http://localhost:9000
2022-09-30 14:50:24.719236+00:00ℹ [HPM] Proxy rewrite rule created: "api" ~> "/api"
2022-09-30 14:50:24.720008+00:00ℹ [HPM] Proxy created: /api  -> http://localhost:9000
2022-09-30 14:50:25.341740+00:00ℹ Listening on: http://172.17.0.24:3001/

- - -

Application Name: mealie-9-0-3
Pod Name: mealie-9-0-3-5bf8f6658-s4jpp
Container Name: prepare

2022-09-30 14:44:44.589034+00:00Automatically correcting permissions...
2022-09-30 14:44:44.670539+00:00mealie-9-0-3-postgresql:5432 - no response
2022-09-30 14:44:46.717887+00:00mealie-9-0-3-postgresql:5432 - no response
2022-09-30 14:44:48.765443+00:00mealie-9-0-3-postgresql:5432 - no response
2022-09-30 14:44:50.814900+00:00mealie-9-0-3-postgresql:5432 - no response
2022-09-30 14:44:52.863288+00:00mealie-9-0-3-postgresql:5432 - no response
2022-09-30 14:44:54.914366+00:00mealie-9-0-3-postgresql:5432 - no response
2022-09-30 14:44:56.961621+00:00mealie-9-0-3-postgresql:5432 - no response
2022-09-30 14:44:59.013747+00:00mealie-9-0-3-postgresql:5432 - no response
2022-09-30 14:45:01.058967+00:00mealie-9-0-3-postgresql:5432 - accepting connections

Application Configuration

All defaults.

Describe the bug

I have a working Mealie deployment version 0.5.6_9.0.3. I am attempting to upgrade to 1.0.0beta_10.0.4.

Attempts to upgrade this instance result in the container never deploying successfully (stuck on deploying 1/2). However, deploying a new instance with the same version is successful. Looking at the logs, I assume that the database migration process from old to new is causing a problem. If I rollback to the prior version of the instance it still works fine.

To Reproduce

  1. Have a working Mealie 0.5.6_9.0.3 deployment.
  2. Use the TrueNAS UI to upgrade the app to the latest, 1.0.0beta_10.0.4.

Expected Behavior

Upgrade is successful, recipes are maintained, user login is maintained.

Screenshots

n/a

Additional Context

May be related to issue #3752.

Rollback to old version is successful.

I've read and agree with the following

stavros-k commented 2 years ago

It’s a double breaking release from both our chart and the upstream project

yottabit42 commented 2 years ago

Ok, does that mean it will not be fixed? As the app itself does not allow restores of backups from prior versions, I guess I will just need to set it up from scratch in the new version.

StevenMcElligott commented 2 years ago

Anytime there's a breaking change you will likely have to set it up from scratch, especially seeing as this is a change upstream (the app itself) and in our chart. Same thing applies if an app moves trains (from incubator to stable and etc. So it's not "not fixed", it's basically stay at your old version or start a new installation

PrivatePuffin commented 2 years ago

Please next time file a ticket on discord accordingly, unless you've some evidence there is a code issue. "Not worky" is not really something we can accept bugreports for and 99% of cases it's usererror.

yottabit42 commented 2 years ago

As an end user of the product, I didn't just write "not worky" and followed all the guidelines for logs. No reason to be rude.

Also as an end user, and this is probably outside of TrueChart's control, but perhaps a feature request that could be made to TrueNAS, is to block/hide upgrades from breaking change versions. For instance it's known now that this app upgrade path doesn't work, due to multiple reasons, so it would be nice if the upgrade option was removed from the UI for this version edge, since the upgrade "not worky."

shadofall commented 2 years ago

or people could realize that major revision number changes (app version or chart version) = possible breaking change. flip side while you cant import your backup to restore all the settings. you can import the backup to restore recipes using the migration option as per document on the applications site.

StevenMcElligott commented 2 years ago

As an end user of the product, I didn't just write "not worky" and followed all the guidelines for logs. No reason to be rude.

Also as an end user, and this is probably outside of TrueChart's control, but perhaps a feature request that could be made to TrueNAS, is to block/hide upgrades from breaking change versions. For instance it's known now that this app upgrade path doesn't work, due to multiple reasons, so it would be nice if the upgrade option was removed from the UI for this version edge, since the upgrade "not worky."

A lot of this is outside control, but he wasn't rude. Basically stated that Discord is the best place for this unless it's really "our" issue, and the changes in the PR you linked shows it's basically a new chart with new release versions, we can't guarantee updates in between major version in the app/chart.

yottabit42 commented 2 years ago

@shadofall thanks for the pointer there. I was able to use the migration feature to import my old backup. It wasn't obvious in the Mealie UI that this was an option, as one wouldn't immediately think that migration from other recipe databases would include prior versions of Mealie itself.

@StevenMcElligott I understand the point. It's just not obvious to an end user that using the UI's built-in upgrade feature should be expected to fail in any circumstance. Thus, I expected this was an issue with the upgrade pipeline, not a breaking change where I should expect the upgrade to lose state. Now I know, but I am sure this will continue coming up forever with the current method in the TrueNAS UI.

Thanks everyone!

PrivatePuffin commented 2 years ago

As an end user of the product, I didn't just write "not worky" and followed all the guidelines for logs. No reason to be rude.

I'm not much a fan of terms like "end user of the product". Because in my opinion it sounds like you expect a reply like customer of a product would, which is simply almost never what you're going to get with free (as in beer) software.

Anyway: What I tried to explain is that "failed to deploy" is basically similair to "not worky". We don't want to burden our developers, with verifying vague reports of "something", "not working".

We have very clear support procedures in place, in case people experience something not working correctly, which sometimes advice to file a bugreport. As our limited amount of maintainers is not able to also verify each and every user that reports a "not working" app, which 99% of cases ends up being user error.

@StevenMcElligott I understand the point. It's just not obvious to an end user that using the UI's built-in upgrade feature should be expected to fail in any circumstance. Thus, I expected this was an issue with the upgrade pipeline, not a breaking change where I should expect the upgrade to lose state. Now I know, but I am sure this will continue coming up forever with the current method in the TrueNAS UI.

To be 100% clear: SemVer is our choice, not all projects follow semver. For example: some use CalVer or a variant of that (like iX Systems). So it's not something iX is likely te put into the GUI.

It's your responsibility to read the changelog on upgrade, though we agree our changelog needs work to more clearly/cleanly define breaking change update (aka major version increases). But byond that, there is not much we can do. But besides that "breaking" just means "something might break", it does not mean "verified to be breaking something", so completely hiding things away on each major upgrade would also be silly.

In the end, we are making design decisions for the Apps, but we're also allowing Apps we deem "trash quality" or "unstable". We've decided that we leave it to the user to decide what they want to install and upgrade, hence we expect users to read the docs and changelogs of everything they install.

In the future we will be dedicating a seperate train for things we've verified, thoroughly tested and take more responsibility for (from a security and stability perspective).

So in that regard: we're not likely to do anything with this feedback and I expect neither is iX (actually: I discussed that previously a year ago and no, I personally did not agree there).

truecharts-admin commented 1 year ago

This issue is locked to prevent necro-posting on closed issues. Please create a new issue or contact staff on discord of the problem persists