Owncloud update failed error 416

sasa · May 31, 2019, 8:52am

Hello,

I tried to upgrade owncloud, but something went wrong and left me with owncloud uninstalled.
Server diary log says: “Die Aktualisierung von ownCloud 10.0.10-2019-02-18 schlug fehl. Fehler 416”-> english:“Update of owncloud… failed. Error 416”.

I tried to reinstall owncloud but it’s always aborting in between:

 univention-app install owncloud

Starting redis ...
Starting owncloud ...
 tarting owncloud ... done
Running command: docker cp /etc/owncloud.secret ...:/etc/owncloud.secret
Configuring 4.3/owncloud=10.1.1-2019-04-23
Module: kopano-cfg
Module: autostart
ucr cannot be found, falling back to changing the database file directly
Executing interface restore_data_before_setup for owncloud
No interface defined
Executing interface setup for owncloud
Copying App Center's setup to container's /usr/share/univention-docker-container-mode/setup_custom
[02.DOCKER_SETUP] Enable user_ldap app
..
Setup script failed!
Aborting...
Going to remove ownCloud (10.1.1-2019-04-23)

Here’s some part of the appcenter.log:

....
12164 actions.install                  19-06-05 22:28:36 [    INFO]: Verifying Docker registry manifest for app image docker.software-univention.de/owncloud-owncloud:10.1.1-2019-04-23
 12164 actions.install                  19-06-05 22:28:36 [    INFO]: Downloading app images
 12164 docker                           19-06-05 22:28:36 [   DEBUG]: Running in /var/lib/univention-appcenter/apps/owncloud/compose:
 12164 docker                           19-06-05 22:28:36 [    INFO]: Running command: docker-compose -p owncloud pull
 12164 docker                           19-06-05 22:28:38 [    INFO]: Pulling redis (docker.software-univention.de/owncloud-redis:10.1.1-2019-04-23)...
 12164 docker                           19-06-05 22:28:41 [    INFO]: 10.1.1-2019-04-23: Pulling from owncloud-redis
 12164 docker                           19-06-05 22:28:41 [    INFO]: Digest: sha256:6c434f25a5e6c3eff1867893eed9cd21bf9864b279f171a8267493749c5f4597
 12164 docker                           19-06-05 22:28:41 [    INFO]: Status: Image is up to date for docker.software-univention.de/owncloud-redis:10.1.1-2019-04-23
 12164 docker                           19-06-05 22:28:41 [    INFO]: Pulling owncloud (docker.software-univention.de/owncloud-owncloud:10.1.1-2019-04-23)...
 12164 docker                           19-06-05 22:28:42 [    INFO]: 10.1.1-2019-04-23: Pulling from owncloud-owncloud
 12164 docker                           19-06-05 22:28:42 [    INFO]: Digest: sha256:efdaf93e7232d9da2836b7505ce70a468fbabc4e463118a34788a923a18cb17f
 12164 docker                           19-06-05 22:28:42 [    INFO]: Status: Image is up to date for docker.software-univention.de/owncloud-owncloud:10.1.1-2019-04-23
 12164 actions.install                  19-06-05 22:28:42 [    INFO]: Initializing app image
 12164 database                         19-06-05 22:28:42 [   DEBUG]: 4.3/owncloud=10.1.1-2019-04-23 uses MySQL
 12164 actions.install                  19-06-05 22:28:53 [    INFO]: Preconfiguring container 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02
 12164 actions.start                    19-06-05 22:28:53 [   DEBUG]: Calling start
 12164 actions.start.progress           19-06-05 22:28:53 [   DEBUG]: 0
 12164 docker                           19-06-05 22:28:53 [   DEBUG]: Calling in /var/lib/univention-appcenter/apps/owncloud/compose:
 12164 docker                           19-06-05 22:28:53 [   DEBUG]: Calling docker-compose -p owncloud start
 12164 docker                           19-06-05 22:28:53 [ WARNING]: Starting redis ... 
 12164 docker                           19-06-05 22:28:53 [ WARNING]: Starting owncloud ... 
 12164 docker                           19-06-05 22:28:53 [ WARNING]:   
Starting redis ... done
   
Starting owncloud ... done
 
 12164 actions.start.progress           19-06-05 22:28:53 [   DEBUG]: 100
 12164 docker.base.container.006e       19-06-05 22:28:57 [   DEBUG]: Using container.006e for container 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02
 12164 docker.base.container.006e       19-06-05 22:28:57 [   DEBUG]: Calling docker exec -u root -it 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02 rm -f /etc/timezone /etc/localtime
 12164 docker.base                      19-06-05 22:28:57 [    INFO]: Running command: docker cp /etc/timezone 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02:/etc/timezone
 12164 docker.base                      19-06-05 22:28:58 [    INFO]: Running command: docker cp /etc/localtime 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02:/etc/localtime
 12164 actions.install                  19-06-05 22:28:58 [    INFO]: Running command: docker cp /etc/owncloud.secret 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02:/etc/owncloud.secret
 12164 actions.configure                19-06-05 22:28:59 [   DEBUG]: Calling configure
 12164 actions.configure.progress       19-06-05 22:28:59 [   DEBUG]: 0
 12164 actions.configure                19-06-05 22:28:59 [    INFO]: Configuring 4.3/owncloud=10.1.1-2019-04-23
 12164 docker.configure.container.006e  19-06-05 22:29:01 [   DEBUG]: Using container.006e for container 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02
 12164 docker.configure.container.006e  19-06-05 22:29:01 [   DEBUG]: Calling docker exec -u root -it 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02 which ucr
 12164 actions.configure                19-06-05 22:29:01 [ WARNING]: ucr cannot be found, falling back to changing the database file directly
 12164 actions.configure.progress       19-06-05 22:29:01 [   DEBUG]: 100
 12164 actions.install.progress         19-06-05 22:29:01 [   DEBUG]: 50
 12164 actions.install                  19-06-05 22:29:01 [    INFO]: Executing interface restore_data_before_setup for owncloud
 12164 actions.install                  19-06-05 22:29:01 [    INFO]: No interface defined
 12164 actions.install                  19-06-05 22:29:01 [    INFO]: Executing interface setup for owncloud
 12164 actions.install                  19-06-05 22:29:01 [    INFO]: Copying App Center's setup to container's /usr/share/univention-docker-container-mode/setup_custom
 12164 actions.install.container.006e   19-06-05 22:29:01 [   DEBUG]: Using container.006e for container 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02
 12164 actions.install.container.006e   19-06-05 22:29:01 [   DEBUG]: Calling docker exec -u root 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02 /usr/share/univention-docker-container-mode/setup_custom --username Administrator --app owncloud --error-file /var/univention/tmp/tmp4DghEV --password-file /var/univention/tmp/tmptKoA4H --app-version 10.1.1-2019-04-23
 12164 actions.install.container.006e   19-06-05 22:29:26 [    INFO]: [02.DOCKER_SETUP] Enable user_ldap app
 12164 actions.install.container.006e   19-06-05 22:29:33 [    INFO]: ..
 12164 packages                         19-06-05 22:29:33 [   DEBUG]: Releasing LOCK
 12164 actions.install                  19-06-05 22:29:33 [CRITICAL]: Setup script failed!
 12164 actions.install                  19-06-05 22:29:33 [ WARNING]: Aborting...
 12164 actions.remove                   19-06-05 22:29:33 [   DEBUG]: Calling remove
 12164 actions.remove.progress          19-06-05 22:29:33 [   DEBUG]: 0
 12164 actions.remove                   19-06-05 22:29:33 [    INFO]: Going to remove ownCloud (10.1.1-2019-04-23)
 12164 actions.remove                   19-06-05 22:29:33 [   DEBUG]: Calling prescript (prerm)
 12164 actions.remove                   19-06-05 22:29:33 [   DEBUG]: Calling /var/cache/univention-appcenter/appcenter.software-univention.de/4.3/owncloud_20190226114121.prerm --locale de --binddn uid=Administrator,cn=users,dc=msc-vaale-e-v,dc=intranet --bindpwdfile /tmp/tmpTGI0ks --version 10.1.1-2019-04-23 --error-file /tmp/tmpGzziIx
 12164 actions.remove                   19-06-05 22:29:33 [    INFO]: [PRE_RM]
 12164 actions.remove                   19-06-05 22:29:34 [   DEBUG]: /var/cache/univention-appcenter/appcenter.software-univention.de/4.3/owncloud_20190226114121.prerm returned with 0
 12164 packages                         19-06-05 22:29:34 [   DEBUG]: Holding LOCK
 12164 actions.remove.progress          19-06-05 22:29:37 [   DEBUG]: 5
 12164 actions.remove.progress          19-06-05 22:29:37 [   DEBUG]: 5
 12164 actions.configure                19-06-05 22:29:37 [   DEBUG]: Calling configure
 12164 actions.configure.progress       19-06-05 22:29:37 [   DEBUG]: 0
 12164 actions.configure                19-06-05 22:29:37 [    INFO]: Configuring 4.3/owncloud=10.1.1-2019-04-23
 12164 actions.configure                19-06-05 22:29:37 [   DEBUG]: Calling /var/cache/univention-appcenter/appcenter.software-univention.de/4.3/owncloud_20190226114121.configure_host remove --locale de --version 10.1.1-2019-04-23 --error-file /tmp/tmp_6tpOz
 12164 actions.configure                19-06-05 22:29:37 [   DEBUG]: /var/cache/univention-appcenter/appcenter.software-univention.de/4.3/owncloud_20190226114121.configure_host returned with 0
 12164 actions.configure                19-06-05 22:29:37 [    INFO]: Executing interface configure for owncloud
 12164 actions.configure                19-06-05 22:29:37 [    INFO]: Copying App Center's configure to container's /tmp/configure
 12164 actions.configure.container.006e 19-06-05 22:29:37 [   DEBUG]: Using container.006e for container 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02
 12164 actions.configure.container.006e 19-06-05 22:29:37 [   DEBUG]: Calling docker exec -u root 006e8f83c7d4011fc45c6964cb83be2dca43f049db8404197044e7995fab4c02 /tmp/configure remove --app owncloud --app-version 10.1.1-2019-04-23 --error-file /var/univention/tmp/tmpQnt0PN
 12164 actions.configure.container.006e 19-06-05 22:29:38 [ WARNING]: configuration script running...
 12164 actions.configure.progress       19-06-05 22:29:38 [   DEBUG]: 100
 12164 actions.stop                     19-06-05 22:29:38 [   DEBUG]: Calling stop
 12164 actions.stop.progress            19-06-05 22:29:38 [   DEBUG]: 0
 12164 docker                           19-06-05 22:29:38 [   DEBUG]: Calling in /var/lib/univention-appcenter/apps/owncloud/compose:
 12164 docker                           19-06-05 22:29:38 [   DEBUG]: Calling docker-compose -p owncloud stop
 12164 docker                           19-06-05 22:29:39 [ WARNING]: Stopping owncloud_owncloud_1 ... 
 12164 docker                           19-06-05 22:29:39 [ WARNING]: Stopping owncloud_redis_1 ... 
 12164 docker                           19-06-05 22:29:48 [ WARNING]:   
Stopping owncloud_owncloud_1 ... done
...

Some info about my System:

UCS: 4.4-0 errata137
Installed: admindiary-backend=1.0 admindiary-frontend=1.0 kvm=2.8 letsencrypt=1.2.2-8 radius=5.0 samba4=4.10 self-service=4.0 uvmm=7.0 4.3/dudle=1.2.0-1 4.3/onlyoffice-ds=5.3.0.243
Upgradable:

The reinstall seems to hang on the “Enable user_ldap app” item. How can I further debug this?
Anybody knowing this issue?
How can I determine what error 416 means?

Kind regards,
Sascha

gulden · June 7, 2019, 12:52pm

Hello @sasa,

there is a similar posting at Owncloud 10.1.1-2019-04-23 - Cannot upgrade - RESOLVED. Maybe this already helps.

Error 416 means that something during the app setup went wrong. The upgrade is aborted and
the App Center then tries to return to the last status and installs the previous version. This fails, because the ownCloud app expects an empty database upon installation and there uninstalls the app.

A fix for this problem is work in progress.

Best regards,
Nico

Charles · July 3, 2019, 10:26am

Hello, Nico.

When is the patch for this scheduled to be released?

This failed update remains a problem for me, and possibly many other users.

In my case, the installation is a simple one using the Univention OwnCloud appliance in a VirtualBox container. I attempted to apply the update as recommended by a pop-up in the Univention management console, and the installation failed. An attempted re-installation of OwnCloud also fails.

An update that doesn’t permit rollback after failure is perhaps a problem that should be rapidly fixed.

Would you kindly update us?

Best regards,
Charles

gulden · July 4, 2019, 2:02pm

Hello @Charles,

an app update has already been released that should fix the issues that have been found. Maybe your system is currently in a status that may require manual steps. Can you please elaborate what your steps have been, what your current status is in detail and what version (UCS and ownCloud) you have?

Edit: Maybe this article helps to collect additional information: Docker App Upgrade Troubleshooting

Best regards,
Nico

sasa · July 4, 2019, 2:49pm

Hi Nico,

I’ve just read here that there has been an update. I did not see any update in the web frontend, so I did an extra apt-get update/upgrade.
I tried to remove my not working owncloud installation with univention-app remove owncloud --do-not-backup.
Afterwards I did a reboot and tried to install owncloud again.
I got the same error as before:

Starting redis ...
Starting owncloud ...
 tarting owncloud ... done
Running command: docker cp /etc/owncloud.secret 5b45b0c7cd446845127ab221ce4bd12c                                                                                                                                                             d35d662ff2a37743e0074075e1b4913c:/etc/owncloud.secret
Configuring 4.3/owncloud=10.1.1-2019-04-23
Module: kopano-cfg
Module: autostart
ucr cannot be found, falling back to changing the database file directly
Executing interface restore_data_before_setup for owncloud
No interface defined
Executing interface setup for owncloud
Copying App Center's setup to container's /usr/share/univention-docker-container                                                                                                                                                             -mode/setup_custom
[02.DOCKER_SETUP] Enable user_ldap app
..
Setup script failed!
Aborting...
Going to remove ownCloud (10.1.1-2019-04-23)
[PRE_RM]
Module: kopano-cfg
Configuring 4.3/owncloud=10.1.1-2019-04-23
Executing interface configure for owncloud
Copying App Center's configure to container's /tmp/configure
configuration script running...
Stopping owncloud_owncloud_1 ...
Stopping owncloud_redis_1 ...
 topping owncloud_redis_1 ... doneone
Removing owncloud_owncloud_1 ...
Removing owncloud_redis_1 ...
Going to remove owncloud_owncloud_1, owncloud_redis_1
 emoving owncloud_redis_1 ... doneone
Removing localhost from LDAP object
File: /usr/share/univention-portal/apps.json
File: /etc/univention/service.info/services/univention-appcent'

My UCS version is: 4.4-0 errata175
Owncloud version to install was: 10.1.1-2019-04-23

Do you have a hint what manual steps I have to do?

Kind regards,
Sascha