Owncloud update failed error 416

owncloud
update

#1

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


#2

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