Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Nextcloud 14.0.0 beta 3 updater "ocm-provider" extra file #10677

Closed
Matei-Ciobotaru opened this issue Aug 13, 2018 · 35 comments
Closed

Nextcloud 14.0.0 beta 3 updater "ocm-provider" extra file #10677

Matei-Ciobotaru opened this issue Aug 13, 2018 · 35 comments
Labels

Comments

@Matei-Ciobotaru
Copy link

Matei-Ciobotaru commented Aug 13, 2018

Steps to reproduce

  1. Update NC 14.0.0 beta 1 to NC 14.0.0 beta 3 via web updater

Expected behaviour

The NC update should be implemented

Actual behaviour

I receive an error at the first step of the update ("Check for expected files") stating that an extra file has been found ("ocm-provider")

screenshot from 2018-08-13 19-27-37_cropped

Server configuration

Operating system: Raspbian Strech 9.4

Web server: Apache/2.4.25 (Raspbian)

Database: mysql Ver 15.1 Distrib 10.1.23-MariaDB, for debian-linux-gnueabihf (armv7l) using readline 5.2

PHP version: PHP 7.0.30-0+deb9u1 (cli) (built: Jun 14 2018 13:50:25) ( NTS )

Nextcloud version: Nextcloud 14.0.0 beta 1

Updated from an older Nextcloud/ownCloud or fresh install: updated

Where did you install Nextcloud from: The official site

Signing status:

Signing status
Not found...

List of activated apps:

App list

Enabled:

  • accessibility: 1.0.1
  • activity: 2.7.0
  • bruteforcesettings: 1.1.0
  • calendar: 1.6.1
  • cloud_federation_api: 0.0.1
  • comments: 1.4.0
  • dav: 1.6.0
  • federatedfilesharing: 1.4.0
  • federation: 1.4.0
  • files: 1.9.0
  • files_pdfviewer: 1.3.2
  • files_sharing: 1.6.2
  • files_texteditor: 2.6.0
  • files_trashbin: 1.4.1
  • files_versions: 1.7.1
  • files_videoplayer: 1.3.0
  • firstrunwizard: 2.3.0
  • gallery: 18.1.0
  • logreader: 2.0.0
  • lookup_server_connector: 1.2.0
  • nextcloud_announcements: 1.3.0
  • notifications: 2.2.1
  • oauth2: 1.2.1
  • password_policy: 1.4.0
  • provisioning_api: 1.4.0
  • serverinfo: 1.4.0
  • sharebymail: 1.4.0
  • support: 1.0.0
  • survey_client: 1.2.0
  • systemtags: 1.4.0
  • theming: 1.5.0
  • twofactor_backupcodes: 1.3.0
  • updatenotification: 1.4.1
  • workflowengine: 1.4.0
    Disabled:
  • admin_audit
  • encryption
  • files_external
  • quota_warning
  • ransomware_protection
  • user_external
  • user_ldap

Nextcloud configuration:

Config report

:~# sudo -u www-data php /var/www/nextcloud/occ config:list
{
"system": {
"instanceid": "REMOVED SENSITIVE VALUE",
"passwordsalt": "REMOVED SENSITIVE VALUE",
"secret": "REMOVED SENSITIVE VALUE",
"trusted_domains": [
"REMOVED SENSITIVE VALUE",
"192.168.1.12"
],
"datadirectory": "REMOVED SENSITIVE VALUE",
"overwrite.cli.url": "http://nimbus.go.ro/nextcloud",
"dbtype": "mysql",
"version": "14.0.0.13",
"dbname": "REMOVED SENSITIVE VALUE",
"dbhost": "REMOVED SENSITIVE VALUE",
"dbport": "",
"dbtableprefix": "oc_",
"mysql.utf8mb4": true,
"dbuser": "REMOVED SENSITIVE VALUE",
"dbpassword": "REMOVED SENSITIVE VALUE",
"installed": true,
"memcache.local": "\OC\Memcache\APCu",
"redis": {
"host": "REMOVED SENSITIVE VALUE",
"port": 0,
"dbindex": 0,
"timeout": 1.5
},
"memcache.locking": "\OC\Memcache\Redis",
"maintenance": false,
"log_type": "file",
"logfile": "/var/log/nextcloud.log",
"log_level": 0,
"logtimezone": "Europe/Bucharest",
"log_rotate_size": "104857600",
"theme": "",
"updater.release.channel": "beta",
"loglevel": 2,
"updater.secret": "REMOVED SENSITIVE VALUE"
},
"apps": {
"accessibility": {
"enabled": "yes",
"installed_version": "1.0.1",
"types": ""
},
"activity": {
"enabled": "yes",
"installed_version": "2.7.0",
"notify_email_calendar": "0",
"notify_email_calendar_event": "0",
"notify_email_calendar_todo": "0",
"notify_email_comments": "0",
"notify_email_favorite": "0",
"notify_email_file_changed": "0",
"notify_email_file_created": "0",
"notify_email_file_deleted": "0",
"notify_email_file_restored": "0",
"notify_email_public_links": "0",
"notify_email_remote_share": "0",
"notify_email_shared": "0",
"notify_email_systemtags": "0",
"notify_setting_batchtime": "604800",
"notify_setting_self": "1",
"notify_setting_selfemail": "0",
"notify_stream_calendar": "1",
"notify_stream_calendar_event": "1",
"notify_stream_calendar_todo": "1",
"notify_stream_comments": "1",
"notify_stream_favorite": "1",
"notify_stream_file_changed": "1",
"notify_stream_file_created": "1",
"notify_stream_file_deleted": "1",
"notify_stream_file_favorite": "0",
"notify_stream_file_restored": "1",
"notify_stream_public_links": "1",
"notify_stream_remote_share": "1",
"notify_stream_shared": "1",
"notify_stream_systemtags": "1",
"types": "filesystem"
},
"backgroundjob": {
"lastjob": "42"
},
"bruteforcesettings": {
"enabled": "yes",
"installed_version": "1.1.0",
"types": ""
},
"calendar": {
"enabled": "yes",
"installed_version": "1.6.1",
"types": ""
},
"cloud_federation_api": {
"enabled": "yes",
"installed_version": "0.0.1",
"types": "filesystem"
},
"comments": {
"enabled": "yes",
"installed_version": "1.4.0",
"types": "logging"
},
"core": {
"backgroundjobs_mode": "cron",
"installed.bundles": "["CoreBundle"]",
"installedat": "1522146265.8253",
"lastcron": "1534177801",
"lastupdateResult": "{"version":"14.0.0.15","versionstring":"Nextcloud 14.0.0 Beta 3","url":"https:\/\/download.nextcloud.com\/server\/prereleases\/nextcloud-14.0.0beta3.zip","web":"https:\/\/docs.nextcloud.com\/server\/13\/admin_manual\/maintenance\/upgrade.html","changes":"","autoupdater":"1","eol":"0"}",
"lastupdatedat": "1534177621",
"moveavatarsdone": "yes",
"oc.integritycheck.checker": "[]",
"previewsCleanedUp": "1",
"public_files": "files_sharing/public.php",
"public_webdav": "dav/appinfo/v1/publicwebdav.php",
"scss.variables": "77aa2c892fabd342d0d904f3d66da905",
"updater.secret.created": "1534177637",
"vendor": "nextcloud"
},
"dav": {
"buildCalendarSearchIndex": "yes",
"enabled": "yes",
"installed_version": "1.6.0",
"sendInvitations": "no",
"types": "filesystem"
},
"federatedfilesharing": {
"enabled": "yes",
"installed_version": "1.4.0",
"types": ""
},
"federation": {
"enabled": "yes",
"installed_version": "1.4.0",
"types": "authentication"
},
"files": {
"cronjob_scan_files": "500",
"enabled": "yes",
"installed_version": "1.9.0",
"types": "filesystem"
},
"files_pdfviewer": {
"enabled": "yes",
"installed_version": "1.3.2",
"types": ""
},
"files_sharing": {
"enabled": "yes",
"installed_version": "1.6.2",
"types": "filesystem"
},
"files_texteditor": {
"enabled": "yes",
"installed_version": "2.6.0",
"types": ""
},
"files_trashbin": {
"enabled": "yes",
"installed_version": "1.4.1",
"types": "filesystem,dav"
},
"files_versions": {
"enabled": "yes",
"installed_version": "1.7.1",
"types": "filesystem,dav"
},
"files_videoplayer": {
"enabled": "yes",
"installed_version": "1.3.0",
"types": ""
},
"firstrunwizard": {
"enabled": "yes",
"installed_version": "2.3.0",
"types": "logging"
},
"gallery": {
"enabled": "yes",
"installed_version": "18.1.0",
"types": ""
},
"logreader": {
"enabled": "yes",
"installed_version": "2.0.0",
"ocsid": "170871",
"types": ""
},
"lookup_server_connector": {
"enabled": "yes",
"installed_version": "1.2.0",
"types": "authentication"
},
"nextcloud_announcements": {
"enabled": "yes",
"installed_version": "1.3.0",
"pub_date": "Sat, 10 Dec 2016 00:00:00 +0100",
"types": "logging"
},
"notifications": {
"enabled": "yes",
"installed_version": "2.2.1",
"types": "logging"
},
"oauth2": {
"enabled": "yes",
"installed_version": "1.2.1",
"types": "authentication"
},
"password_policy": {
"enabled": "yes",
"enforceNumericCharacters": "1",
"enforceSpecialCharacters": "1",
"enforceUpperLowerCase": "1",
"installed_version": "1.4.0",
"types": ""
},
"provisioning_api": {
"enabled": "yes",
"installed_version": "1.4.0",
"types": "prevent_group_restriction"
},
"quota_warning": {
"enabled": "no",
"installed_version": "1.2.0",
"types": "filesystem"
},
"ransomware_protection": {
"enabled": "no",
"installed_version": "1.1.1",
"types": "logging"
},
"serverinfo": {
"enabled": "yes",
"installed_version": "1.4.0",
"types": ""
},
"sharebymail": {
"enabled": "yes",
"installed_version": "1.4.0",
"types": "filesystem"
},
"support": {
"enabled": "yes",
"installed_version": "1.0.0",
"types": ""
},
"survey_client": {
"enabled": "yes",
"installed_version": "1.2.0",
"types": ""
},
"systemtags": {
"enabled": "yes",
"installed_version": "1.4.0",
"types": "logging"
},
"theming": {
"cachebuster": "40",
"color": "#111111",
"enabled": "yes",
"installed_version": "1.5.0",
"name": "Nimbus",
"slogan": "Nothing to see here, move along.",
"types": "logging",
"url": "https://piska.go.ro/"
},
"twofactor_backupcodes": {
"enabled": "yes",
"installed_version": "1.3.0",
"types": ""
},
"updatenotification": {
"bruteforcesettings": "1.1.0",
"core": "14.0.0.15",
"enabled": "yes",
"files_pdfviewer": "1.2.1",
"installed_version": "1.4.1",
"theming": "1.4.5",
"types": "",
"update_check_errors": "0"
},
"workflowengine": {
"enabled": "yes",
"installed_version": "1.4.0",
"types": "filesystem"
}
}
}

~:#

Are you using external storage, if yes which one: /mnt/store

Are you using encryption: no

Are you using an external user-backend, if yes which one: No

Client configuration

Browser: Mozilla Firefox Quantum 59.0.2 for Fedora

Operating system: Fedora 27 Workstation

Logs

Web server error log

No error logged in Apache2's error log.

Nextcloud log (data/nextcloud.log)

Nextcloud log
{"reqId":"W3GxN8CoAQwAABMEXVAAAAAB","level":3,"time":"2018-08-13T19:26:31+03:00","remoteAddr":"188.26.11.173","user":"pisky","app":"PHP","method":"GET","url":"\/cloud\/index.php\/apps\/files\/","message":"Undefined index: classes at \/var\/www\/nextcloud\/apps\/files\/templates\/appnavigation.php#67","userAgent":"Mozilla\/5.0 (X11; Fedora; Linux x86_64; rv:59.0) Gecko\/20100101 Firefox\/59.0","version":"14.0.0.13"}
{"reqId":"W3GxN8CoAQwAABMEXVAAAAAB","level":3,"time":"2018-08-13T19:26:31+03:00","remoteAddr":"188.26.11.173","user":"pisky","app":"PHP","method":"GET","url":"\/cloud\/index.php\/apps\/files\/","message":"Undefined index: classes at \/var\/www\/nextcloud\/apps\/files\/templates\/appnavigation.php#67","userAgent":"Mozilla\/5.0 (X11; Fedora; Linux x86_64; rv:59.0) Gecko\/20100101 Firefox\/59.0","version":"14.0.0.13"}
{"reqId":"W3GxN8CoAQwAABMEXVAAAAAB","level":3,"time":"2018-08-13T19:26:31+03:00","remoteAddr":"188.26.11.173","user":"pisky","app":"PHP","method":"GET","url":"\/cloud\/index.php\/apps\/files\/","message":"Undefined index: classes at \/var\/www\/nextcloud\/apps\/files\/templates\/appnavigation.php#67","userAgent":"Mozilla\/5.0 (X11; Fedora; Linux x86_64; rv:59.0) Gecko\/20100101 Firefox\/59.0","version":"14.0.0.13"}
{"reqId":"W3GxN8CoAQwAABMEXVAAAAAB","level":3,"time":"2018-08-13T19:26:31+03:00","remoteAddr":"188.26.11.173","user":"pisky","app":"PHP","method":"GET","url":"\/cloud\/index.php\/apps\/files\/","message":"Undefined index: classes at \/var\/www\/nextcloud\/apps\/files\/templates\/appnavigation.php#67","userAgent":"Mozilla\/5.0 (X11; Fedora; Linux x86_64; rv:59.0) Gecko\/20100101 Firefox\/59.0","version":"14.0.0.13"}

Browser log

Browser log

I doubt it has anything to do with the browser.

@nextcloud-bot
Copy link
Member

GitMate.io thinks possibly related issues are #10631 (Nextcloud 14 Beta 3 update failed), #10502 (Nextcloud 14 Beta 3), #10604 (Nextcloud 14.0.0 beta3), #9911 (Nextcloud upgrade to 13.0.4 Failed), and #10158 (occ: user:list missing "numeric" username).

@Matei-Ciobotaru
Copy link
Author

Matei-Ciobotaru commented Aug 13, 2018

The workaround for this issue is to remove the specified directory that the web updater complains of, yet I strongly suspect that directory was created by the update to NC 14.0.0 beta 1 since it has the same time stamp as when I updated to v14.0.0 beta 1 (27.07.2018)

Steps I took to bypass the issue:

root@localhost:~# find /var/www/nextcloud/ -name "ocm-provider"
/var/www/nextcloud/ocm-provider
root@localhost:~# mv /var/www/nextcloud/ocm-provider /root/
root@locahost:~# ll /root/
total 4.0K
drwxr-xr-x 2 www-data www-data 4.0K Jul 27 14:19 ocm-provider
root@localhost:~#
root@localhost:~# ll /root/ocm-provider/
total 4.0K
-rw-r--r-- 1 www-data www-data 1.4K Jul 27 14:19 index.php
root@localhost:~# 

After moving the file, the update process went smoothly and I'm now running v 14.0.0 beta 3

I've attached the contents of thee "index.php" script here.

index.txt

@MorrisJobke
Copy link
Member

usually was fixed by nextcloud/updater@1128773

🤔

@ChristophWurst
Copy link
Member

usually was fixed by nextcloud/updater@1128773

In theory, yes. But it wasn't there. I ran into the very same issue when I tried to upgrade from beta 1 (or was it 2?) to beta 3. Is there any possibility that the updater does not replace the updater folder?

@MorrisJobke
Copy link
Member

Is there any possibility that the updater does not replace the updater folder?

Usually not ... :/ anything in the updater.log in the data dir?

@ChristophWurst
Copy link
Member

My log from the day where I had the issue:

2018-08-09T11:58:47+0200 64VwhTe9cy [info] request to updater
2018-08-09T11:58:47+0200 64VwhTe9cy [info] currentStep()
2018-08-09T11:58:47+0200 64VwhTe9cy [info] show HTML page
2018-08-09T11:58:47+0200 64VwhTe9cy [info] current version: 14.0.0 Beta 2 build time: 2018-08-02T09:07:52+00:00 9891eae232d77391699d3be908ed03426779d4d2
2018-08-09T11:58:47+0200 64VwhTe9cy [info] getUpdateServerResponse()
2018-08-09T11:58:47+0200 64VwhTe9cy [info] updaterServer: https://updates.nextcloud.com/internal_updater_server/
2018-08-09T11:58:47+0200 64VwhTe9cy [info] releaseChannel: beta
2018-08-09T11:58:47+0200 64VwhTe9cy [info] internal version: 14.0.0.14
2018-08-09T11:58:47+0200 64VwhTe9cy [info] updateURL: https://updates.nextcloud.com/internal_updater_server/?version=14x0x0x14xxxbetaxx2018-08-02T09%3A07%3A52%2B00%3A00+9891eae232d77391699d3be908ed03426779d4d2x7x0x30
2018-08-09T11:58:47+0200 64VwhTe9cy [info] getUpdateServerResponse response: Array
(
    [version] => 14.0.0.15
    [versionstring] => Nextcloud 14.0.0 Beta 3
    [url] => https://download.nextcloud.com/server/prereleases/nextcloud-14.0.0beta3.zip
    [web] => https://docs.nextcloud.com/server/13/admin_manual/maintenance/upgrade.html
    [autoupdater] => 1
    [eol] => 0
    [signature] => SGhIjsRh2Tnrgp+OoOCgUFcoTotrkiQB0bk83pJYGpAeSp19VgAHw7OVyqRuRT8j
3bWVFbAhYTOKM/PiBJJvtymmPiiNEQyJ90SA0makQCnC935yo27kg00S+lN0DWGZ
7SYHvhCkRBIl0nuAPxu0gj9woDVgzhYbwVRHkN4vN4ATliNeuTCL+YE+kFWGHvNq
VACv3iSLDPENph0NOiUMvIb4LsTP1JgZ5JRcrVL9C4utb13w1/0rqxkuErOvdJh5
8sd8KjWkUzv31IM5GG2o/wNHMD5u7Ii8DyBQic300YSmNeSOUNUen7ceoCtnuQA+
2vssX1+kbJJAedhKYVHznw==
)

2018-08-09T11:58:47+0200 64VwhTe9cy [info] checkForUpdate() Array
(
    [version] => 14.0.0.15
    [versionstring] => Nextcloud 14.0.0 Beta 3
    [url] => https://download.nextcloud.com/server/prereleases/nextcloud-14.0.0beta3.zip
    [web] => https://docs.nextcloud.com/server/13/admin_manual/maintenance/upgrade.html
    [autoupdater] => 1
    [eol] => 0
    [signature] => SGhIjsRh2Tnrgp+OoOCgUFcoTotrkiQB0bk83pJYGpAeSp19VgAHw7OVyqRuRT8j
3bWVFbAhYTOKM/PiBJJvtymmPiiNEQyJ90SA0makQCnC935yo27kg00S+lN0DWGZ
7SYHvhCkRBIl0nuAPxu0gj9woDVgzhYbwVRHkN4vN4ATliNeuTCL+YE+kFWGHvNq
VACv3iSLDPENph0NOiUMvIb4LsTP1JgZ5JRcrVL9C4utb13w1/0rqxkuErOvdJh5
8sd8KjWkUzv31IM5GG2o/wNHMD5u7Ii8DyBQic300YSmNeSOUNUen7ceoCtnuQA+
2vssX1+kbJJAedhKYVHznw==
)

2018-08-09T11:58:47+0200 64VwhTe9cy [info] end of checkForUpdate() Update to Nextcloud 14.0.0 Beta 3 available. (channel: "beta")<br /><span class="light">Following file will be downloaded automatically:</span> <code class="light">https://download.nextcloud.com/server/prereleases/nextcloud-14.0.0beta3.zip</code>
2018-08-09T11:58:48+0200 YUwJ8aTFcS [info] request to updater
2018-08-09T11:58:48+0200 YUwJ8aTFcS [info] currentStep()
2018-08-09T11:58:48+0200 YUwJ8aTFcS [info] POST request for step "1"
2018-08-09T11:58:48+0200 YUwJ8aTFcS [info] startStep("1")
2018-08-09T11:58:48+0200 YUwJ8aTFcS [info] checkForExpectedFilesAndFolders()
2018-08-09T11:58:48+0200 YUwJ8aTFcS [error] POST request failed with UpdateException
2018-08-09T11:58:48+0200 YUwJ8aTFcS [error] Exception: UpdateException
Message: 
Code:0
Trace:
#0 /var/www/nextcloud/updater/index.php(1285): Updater->checkForExpectedFilesAndFolders()
#1 {main}
File:/var/www/nextcloud/updater/index.php
Line:383
Data:
Array
(
    [0] => ocm-provider
)


2018-08-09T11:58:48+0200 YUwJ8aTFcS [info] rollbackChanges("1")
2018-08-09T11:58:48+0200 YUwJ8aTFcS [info] unlink .step
2018-08-09T11:58:48+0200 YUwJ8aTFcS [info] end of  rollbackChanges()
2018-08-09T12:26:25+0200 6VmLujsyi9 [info] request to updater
2018-08-09T12:26:25+0200 6VmLujsyi9 [info] currentStep()
2018-08-09T12:26:25+0200 6VmLujsyi9 [info] POST request for step "1"
2018-08-09T12:26:25+0200 6VmLujsyi9 [info] startStep("1")
2018-08-09T12:26:25+0200 6VmLujsyi9 [info] checkForExpectedFilesAndFolders()
2018-08-09T12:26:25+0200 6VmLujsyi9 [error] POST request failed with UpdateException
2018-08-09T12:26:25+0200 6VmLujsyi9 [error] Exception: UpdateException
Message: 
Code:0
Trace:
#0 /var/www/nextcloud/updater/index.php(1285): Updater->checkForExpectedFilesAndFolders()
#1 {main}
File:/var/www/nextcloud/updater/index.php
Line:383
Data:
Array
(
    [0] => ocm-provider
)


2018-08-09T12:26:25+0200 6VmLujsyi9 [info] rollbackChanges("1")
2018-08-09T12:26:25+0200 6VmLujsyi9 [info] unlink .step
2018-08-09T12:26:25+0200 6VmLujsyi9 [info] end of  rollbackChanges()
2018-08-09T12:26:26+0200 3vk0ghPDO7 [info] request to updater
2018-08-09T12:26:26+0200 3vk0ghPDO7 [info] currentStep()
2018-08-09T12:26:26+0200 3vk0ghPDO7 [info] POST request for step "1"
2018-08-09T12:26:26+0200 3vk0ghPDO7 [info] startStep("1")
2018-08-09T12:26:26+0200 3vk0ghPDO7 [info] checkForExpectedFilesAndFolders()
2018-08-09T12:26:26+0200 3vk0ghPDO7 [error] POST request failed with UpdateException
2018-08-09T12:26:26+0200 3vk0ghPDO7 [error] Exception: UpdateException
Message: 
Code:0
Trace:
#0 /var/www/nextcloud/updater/index.php(1285): Updater->checkForExpectedFilesAndFolders()
#1 {main}
File:/var/www/nextcloud/updater/index.php
Line:383
Data:
Array
(
    [0] => ocm-provider
)

2018-08-09T12:26:26+0200 3vk0ghPDO7 [info] rollbackChanges("1")
2018-08-09T12:26:26+0200 3vk0ghPDO7 [info] unlink .step
2018-08-09T12:26:26+0200 3vk0ghPDO7 [info] end of  rollbackChanges()
2018-08-09T12:27:07+0200 b6ktUKhd29 [info] request to updater
2018-08-09T12:27:07+0200 b6ktUKhd29 [info] currentStep()
2018-08-09T12:27:07+0200 b6ktUKhd29 [info] POST request for step "1"
2018-08-09T12:27:07+0200 b6ktUKhd29 [info] startStep("1")
2018-08-09T12:27:07+0200 b6ktUKhd29 [info] checkForExpectedFilesAndFolders()
2018-08-09T12:27:07+0200 b6ktUKhd29 [error] POST request failed with UpdateException
2018-08-09T12:27:07+0200 b6ktUKhd29 [error] Exception: UpdateException
Message: 
Code:0
Trace:
#0 /var/www/nextcloud/updater/index.php(1285): Updater->checkForExpectedFilesAndFolders()
#1 {main}
File:/var/www/nextcloud/updater/index.php
Line:383
Data:
Array
(
    [0] => ocm-provider
)


2018-08-09T12:27:07+0200 b6ktUKhd29 [info] rollbackChanges("1")
2018-08-09T12:27:07+0200 b6ktUKhd29 [info] unlink .step
2018-08-09T12:27:07+0200 b6ktUKhd29 [info] end of  rollbackChanges()
2018-08-09T12:37:04+0200 kf4KNjLz4B [info] request to updater
2018-08-09T12:37:04+0200 kf4KNjLz4B [info] currentStep()
2018-08-09T12:37:04+0200 kf4KNjLz4B [info] POST request for step "1"
2018-08-09T12:37:04+0200 kf4KNjLz4B [info] startStep("1")
2018-08-09T12:37:04+0200 kf4KNjLz4B [info] checkForExpectedFilesAndFolders()
2018-08-09T12:37:04+0200 kf4KNjLz4B [error] POST request failed with UpdateException
2018-08-09T12:37:04+0200 kf4KNjLz4B [error] Exception: UpdateException
Message: 
Code:0
Trace:
#0 /var/www/nextcloud/updater/index.php(1285): Updater->checkForExpectedFilesAndFolders()
#1 {main}
File:/var/www/nextcloud/updater/index.php
Line:383
Data:
Array
(
    [0] => ocm-provider
)


2018-08-09T12:37:04+0200 kf4KNjLz4B [info] rollbackChanges("1")
2018-08-09T12:37:04+0200 kf4KNjLz4B [info] unlink .step
2018-08-09T12:37:04+0200 kf4KNjLz4B [info] end of  rollbackChanges()
2018-08-09T12:48:45+0200 HRkdKgm0qR [info] request to updater
2018-08-09T12:48:45+0200 HRkdKgm0qR [info] currentStep()
2018-08-09T12:48:45+0200 HRkdKgm0qR [info] POST request for step "1"
2018-08-09T12:48:45+0200 HRkdKgm0qR [info] startStep("1")
2018-08-09T12:48:45+0200 HRkdKgm0qR [info] checkForExpectedFilesAndFolders()
2018-08-09T12:48:45+0200 HRkdKgm0qR [info] end of checkForExpectedFilesAndFolders()
2018-08-09T12:48:45+0200 HRkdKgm0qR [info] endStep("1")
2018-08-09T12:48:45+0200 OXibMTVHeb [info] request to updater
2018-08-09T12:48:45+0200 OXibMTVHeb [info] currentStep()
2018-08-09T12:48:45+0200 OXibMTVHeb [info] Step 1 is in state "end".
2018-08-09T12:48:45+0200 OXibMTVHeb [info] POST request for step "2"
2018-08-09T12:48:45+0200 OXibMTVHeb [info] startStep("2")
2018-08-09T12:48:45+0200 OXibMTVHeb [info] checkWritePermissions()
2018-08-09T12:48:45+0200 OXibMTVHeb [info] end of checkWritePermissions()
2018-08-09T12:48:45+0200 OXibMTVHeb [info] endStep("2")
2018-08-09T12:48:45+0200 jqmDYL2qmH [info] request to updater
2018-08-09T12:48:45+0200 jqmDYL2qmH [info] currentStep()
2018-08-09T12:48:45+0200 jqmDYL2qmH [info] Step 2 is in state "end".
2018-08-09T12:48:45+0200 jqmDYL2qmH [info] POST request for step "3"
2018-08-09T12:48:45+0200 jqmDYL2qmH [info] startStep("3")
2018-08-09T12:48:45+0200 jqmDYL2qmH [info] createBackup()
2018-08-09T12:50:02+0200 jqmDYL2qmH [info] end of createBackup()
2018-08-09T12:50:02+0200 jqmDYL2qmH [info] endStep("3")
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] request to updater
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] currentStep()
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] Step 3 is in state "end".
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] POST request for step "4"
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] startStep("4")
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] downloadUpdate()
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] getUpdateServerResponse()
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] updaterServer: https://updates.nextcloud.com/internal_updater_server/
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] releaseChannel: beta
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] internal version: 14.0.0.14
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] updateURL: https://updates.nextcloud.com/internal_updater_server/?version=14x0x0x14xxxbetaxx2018-08-02T09%3A07%3A52%2B00%3A00+9891eae232d77391699d3be908ed03426779d4d2x7x0x30
2018-08-09T12:50:02+0200 E3zYgkfDlF [info] getUpdateServerResponse response: Array
(
    [version] => 14.0.0.15
    [versionstring] => Nextcloud 14.0.0 Beta 3
    [url] => https://download.nextcloud.com/server/prereleases/nextcloud-14.0.0beta3.zip
    [web] => https://docs.nextcloud.com/server/13/admin_manual/maintenance/upgrade.html
    [autoupdater] => 1
    [eol] => 0
    [signature] => SGhIjsRh2Tnrgp+OoOCgUFcoTotrkiQB0bk83pJYGpAeSp19VgAHw7OVyqRuRT8j
3bWVFbAhYTOKM/PiBJJvtymmPiiNEQyJ90SA0makQCnC935yo27kg00S+lN0DWGZ
7SYHvhCkRBIl0nuAPxu0gj9woDVgzhYbwVRHkN4vN4ATliNeuTCL+YE+kFWGHvNq
VACv3iSLDPENph0NOiUMvIb4LsTP1JgZ5JRcrVL9C4utb13w1/0rqxkuErOvdJh5
8sd8KjWkUzv31IM5GG2o/wNHMD5u7Ii8DyBQic300YSmNeSOUNUen7ceoCtnuQA+
2vssX1+kbJJAedhKYVHznw==
)

2018-08-09T12:50:02+0200 E3zYgkfDlF [info] storage location exists
2018-08-09T12:50:03+0200 E3zYgkfDlF [info] end of downloadUpdate()
2018-08-09T12:50:03+0200 E3zYgkfDlF [info] endStep("4")
2018-08-09T12:50:03+0200 u9DNkpHg6V [info] request to updater
2018-08-09T12:50:03+0200 u9DNkpHg6V [info] currentStep()
2018-08-09T12:50:03+0200 u9DNkpHg6V [info] Step 4 is in state "end".
2018-08-09T12:50:03+0200 u9DNkpHg6V [info] POST request for step "5"
2018-08-09T12:50:03+0200 u9DNkpHg6V [info] startStep("5")
2018-08-09T12:50:03+0200 u9DNkpHg6V [info] verifyIntegrity()
2018-08-09T12:50:03+0200 u9DNkpHg6V [info] getUpdateServerResponse()
2018-08-09T12:50:03+0200 u9DNkpHg6V [info] updaterServer: https://updates.nextcloud.com/internal_updater_server/
2018-08-09T12:50:03+0200 u9DNkpHg6V [info] releaseChannel: beta
2018-08-09T12:50:03+0200 u9DNkpHg6V [info] internal version: 14.0.0.14
2018-08-09T12:50:03+0200 u9DNkpHg6V [info] updateURL: https://updates.nextcloud.com/internal_updater_server/?version=14x0x0x14xxxbetaxx2018-08-02T09%3A07%3A52%2B00%3A00+9891eae232d77391699d3be908ed03426779d4d2x7x0x30
2018-08-09T12:50:04+0200 u9DNkpHg6V [info] getUpdateServerResponse response: Array
(
    [version] => 14.0.0.15
    [versionstring] => Nextcloud 14.0.0 Beta 3
    [url] => https://download.nextcloud.com/server/prereleases/nextcloud-14.0.0beta3.zip
    [web] => https://docs.nextcloud.com/server/13/admin_manual/maintenance/upgrade.html
    [autoupdater] => 1
    [eol] => 0
    [signature] => SGhIjsRh2Tnrgp+OoOCgUFcoTotrkiQB0bk83pJYGpAeSp19VgAHw7OVyqRuRT8j
3bWVFbAhYTOKM/PiBJJvtymmPiiNEQyJ90SA0makQCnC935yo27kg00S+lN0DWGZ
7SYHvhCkRBIl0nuAPxu0gj9woDVgzhYbwVRHkN4vN4ATliNeuTCL+YE+kFWGHvNq
VACv3iSLDPENph0NOiUMvIb4LsTP1JgZ5JRcrVL9C4utb13w1/0rqxkuErOvdJh5
8sd8KjWkUzv31IM5GG2o/wNHMD5u7Ii8DyBQic300YSmNeSOUNUen7ceoCtnuQA+
2vssX1+kbJJAedhKYVHznw==
)

2018-08-09T12:50:04+0200 u9DNkpHg6V [info] storage location: /var/www/nextcloud-data/updater-oc7434cf7ac3/downloads/
2018-08-09T12:50:05+0200 u9DNkpHg6V [info] end of verifyIntegrity()
2018-08-09T12:50:05+0200 u9DNkpHg6V [info] endStep("5")
2018-08-09T12:50:05+0200 rplO3jA5KW [info] request to updater
2018-08-09T12:50:05+0200 rplO3jA5KW [info] currentStep()
2018-08-09T12:50:05+0200 rplO3jA5KW [info] Step 5 is in state "end".
2018-08-09T12:50:05+0200 rplO3jA5KW [info] POST request for step "6"
2018-08-09T12:50:05+0200 rplO3jA5KW [info] startStep("6")
2018-08-09T12:50:05+0200 rplO3jA5KW [info] extractDownload()
2018-08-09T12:50:05+0200 rplO3jA5KW [info] storage location: /var/www/nextcloud-data/updater-oc7434cf7ac3/downloads/
2018-08-09T12:50:08+0200 rplO3jA5KW [info] end of extractDownload()
2018-08-09T12:50:08+0200 rplO3jA5KW [info] endStep("6")
2018-08-09T12:50:08+0200 NoZnngHCT3 [info] request to updater
2018-08-09T12:50:08+0200 NoZnngHCT3 [info] currentStep()
2018-08-09T12:50:08+0200 NoZnngHCT3 [info] Step 6 is in state "end".
2018-08-09T12:50:08+0200 NoZnngHCT3 [info] POST request for step "7"
2018-08-09T12:50:08+0200 NoZnngHCT3 [info] startStep("7")
2018-08-09T12:50:08+0200 NoZnngHCT3 [info] setMaintenanceMode("true")
2018-08-09T12:50:08+0200 NoZnngHCT3 [info] configFileName /var/www/nextcloud/updater/../config/config.php
2018-08-09T12:50:08+0200 NoZnngHCT3 [info] end of setMaintenanceMode()
2018-08-09T12:50:08+0200 NoZnngHCT3 [info] endStep("7")
2018-08-09T12:50:08+0200 hocVcxkTNr [info] request to updater
2018-08-09T12:50:08+0200 hocVcxkTNr [info] currentStep()
2018-08-09T12:50:08+0200 hocVcxkTNr [info] Step 7 is in state "end".
2018-08-09T12:50:08+0200 hocVcxkTNr [info] POST request for step "8"
2018-08-09T12:50:08+0200 hocVcxkTNr [info] startStep("8")
2018-08-09T12:50:08+0200 hocVcxkTNr [info] replaceEntryPoints()
2018-08-09T12:50:08+0200 hocVcxkTNr [info] replace index.php
2018-08-09T12:50:08+0200 hocVcxkTNr [info] replace status.php
2018-08-09T12:50:08+0200 hocVcxkTNr [info] replace remote.php
2018-08-09T12:50:08+0200 hocVcxkTNr [info] replace public.php
2018-08-09T12:50:08+0200 hocVcxkTNr [info] replace ocs/v1.php
2018-08-09T12:50:08+0200 h2018-08-09T12:50:08+0200 hocVcxkTNr [info] end of replaceEntryPoints()
2018-08-09T12:50:08+0200 hocVcxkTNr [info] endStep("8")
2018-08-09T12:50:09+0200 OfRa3UuE0e [info] request to updater
2018-08-09T12:50:09+0200 OfRa3UuE0e [info] currentStep()
2018-08-09T12:50:09+0200 OfRa3UuE0e [info] Step 8 is in state "end".
2018-08-09T12:50:09+0200 OfRa3UuE0e [info] POST request for step "9"
2018-08-09T12:50:09+0200 OfRa3UuE0e [info] startStep("9")
2018-08-09T12:50:09+0200 OfRa3UuE0e [info] deleteOldFiles()
2018-08-09T12:50:09+0200 OfRa3UuE0e [info] config sample exists
2018-08-09T12:50:09+0200 OfRa3UuE0e [info] themes README exists
2018-08-09T12:50:11+0200 OfRa3UuE0e [info] end of deleteOldFiles()
2018-08-09T12:50:11+0200 OfRa3UuE0e [info] endStep("9")
2018-08-09T12:50:11+0200 AODAb0RTDK [info] request to updater
2018-08-09T12:50:11+0200 AODAb0RTDK [info] currentStep()
2018-08-09T12:50:11+0200 AODAb0RTDK [info] Step 9 is in state "end".
2018-08-09T12:50:11+0200 AODAb0RTDK [info] POST request for step "10"
2018-08-09T12:50:11+0200 AODAb0RTDK [info] startStep("10")
2018-08-09T12:50:11+0200 AODAb0RTDK [info] moveNewVersionInPlace()
2018-08-09T12:50:11+0200 AODAb0RTDK [info] storage location: /var/www/nextcloud-data/updater-oc7434cf7ac3/downloads/nextcloud/
2018-08-09T12:50:12+0200 AODAb0RTDK [info] end of moveNewVersionInPlace()
2018-08-09T12:50:12+0200 AODAb0RTDK [info] endStep("10")
2018-08-09T12:51:48+0200 BGC87C4LwX [info] request to updater
2018-08-09T12:51:48+0200 BGC87C4LwX [info] currentStep()
2018-08-09T12:51:48+0200 BGC87C4LwX [info] Step 10 is in state "end".
2018-08-09T12:51:48+0200 BGC87C4LwX [info] POST request for step "12"
2018-08-09T12:51:48+0200 BGC87C4LwX [info] startStep("12")
2018-08-09T12:51:48+0200 BGC87C4LwX [info] finalize()
2018-08-09T12:51:48+0200 BGC87C4LwX [info] storage location: /var/www/nextcloud-data/updater-oc7434cf7ac3/downloads/nextcloud/
2018-08-09T12:51:48+0200 BGC87C4LwX [error] POST request failed with other exception
2018-08-09T12:51:48+0200 BGC87C4LwX [error] Exception: Exception
Message: Could not rename /var/www/nextcloud-data/updater-oc7434cf7ac3/downloads/nextcloud/updater/index.php to /var/www/nextcloud/updater/../updater/index.php
Code:0
Trace:
#0 /var/www/nextcloud/updater/index.php(1037): Updater->moveWithExclusions('/var/www/nextcl...', Array)
#1 /var/www/nextcloud/updater/index.php(1322): Updater->finalize()
#2 {main}
File:/var/www/nextcloud/updater/index.php
Line:983

2018-08-09T12:51:48+0200 BGC87C4LwX [info] rollbackChanges("12")
2018-08-09T12:51:48+0200 BGC87C4LwX [info] unlink .step
2018-08-09T12:51:48+0200 BGC87C4LwX [info] rollbackChanges - step >= 7
2018-08-09T12:51:48+0200 BGC87C4LwX [info] end of  rollbackChanges()
ocVcxkTNr [info] replace ocs/v2.php

@ChristophWurst
Copy link
Member

Possibly related: that's what my final updater screen looks like:

bildschirmfoto von 2018-08-16 11-30-33

@ChristophWurst
Copy link
Member

Is there any possibility that the updater does not replace the updater folder?

My logs above tell me that exactly that has happened. It failed to replace the updater.

@MorrisJobke
Copy link
Member

Could not rename /var/www/nextcloud-data/updater-oc7434cf7ac3/downloads/nextcloud/updater/index.php to /var/www/nextcloud/updater/../updater/index.php

Yes ... I have seen this more often in the past, that random files could not be replaced, but no further details why it failed. Is there anything in your syslog?

@ChristophWurst
Copy link
Member

Is there anything in your syslog?

I don't have access to those anymore.

@Matei-Ciobotaru
Copy link
Author

Matei-Ciobotaru commented Aug 21, 2018

Here's an extract for the day of the update from my updater.log, where some [error] POST request failed with UpdateException entries were logged several times.

I've checked the system log for that date and have not found anything relevant.

root@localhost:~ grep '2018-08-13' /mnt/store/nextcloud/updater.log
2018-08-13T19:27:17+0300 3ykS4KEpVc [info] request to updater
2018-08-13T19:27:17+0300 3ykS4KEpVc [info] currentStep()
2018-08-13T19:27:17+0300 3ykS4KEpVc [info] show HTML page
2018-08-13T19:27:17+0300 3ykS4KEpVc [info] current version: 14.0.0 Beta 1 build time: 2018-07-26T11:34:24+00:00 adcc061
2018-08-13T19:27:18+0300 3ykS4KEpVc [info] getUpdateServerResponse()
2018-08-13T19:27:18+0300 3ykS4KEpVc [info] updaterServer: https://updates.nextcloud.org/updater_server/
2018-08-13T19:27:18+0300 3ykS4KEpVc [info] releaseChannel: beta
2018-08-13T19:27:18+0300 3ykS4KEpVc [info] internal version: 14.0.0.13
2018-08-13T19:27:18+0300 3ykS4KEpVc [info] updateURL: https://updates.nextcloud.org/updater_server/?version=14x0x0x13xxxbetaxx2018-07-26T11%3A34%3A24%2B00%3A00+adcc061166ab86bd39b004bd85847320fdd2d525x7x0x30
2018-08-13T19:27:18+0300 3ykS4KEpVc [info] getUpdateServerResponse response: Array
2018-08-13T19:27:18+0300 3ykS4KEpVc [info] checkForUpdate() Array
2018-08-13T19:27:18+0300 3ykS4KEpVc [info] end of checkForUpdate() Update to Nextcloud 14.0.0 Beta 3 available. (channel: "beta")
Following file will be downloaded automatically: https://download.nextcloud.com/server/prereleases/nextcloud-14.0.0beta3.zip
2018-08-13T19:27:22+0300 v8T523XCrk [info] request to updater
2018-08-13T19:27:22+0300 v8T523XCrk [info] currentStep()
2018-08-13T19:27:22+0300 v8T523XCrk [info] POST request for step "1"
2018-08-13T19:27:22+0300 v8T523XCrk [info] startStep("1")
2018-08-13T19:27:22+0300 v8T523XCrk [info] checkForExpectedFilesAndFolders()
2018-08-13T19:27:22+0300 v8T523XCrk [error] POST request failed with UpdateException
2018-08-13T19:27:22+0300 v8T523XCrk [error] Exception: UpdateException
2018-08-13T19:27:22+0300 v8T523XCrk [info] rollbackChanges("1")
2018-08-13T19:27:22+0300 v8T523XCrk [info] unlink .step
2018-08-13T19:27:22+0300 v8T523XCrk [info] end of rollbackChanges()
2018-08-13T19:29:18+0300 G3Rit7sXEa [info] request to updater
2018-08-13T19:29:18+0300 G3Rit7sXEa [info] currentStep()
2018-08-13T19:29:18+0300 G3Rit7sXEa [info] POST request for step "1"
2018-08-13T19:29:18+0300 G3Rit7sXEa [info] startStep("1")
2018-08-13T19:29:18+0300 G3Rit7sXEa [info] checkForExpectedFilesAndFolders()
2018-08-13T19:29:18+0300 G3Rit7sXEa [error] POST request failed with UpdateException
2018-08-13T19:29:18+0300 G3Rit7sXEa [error] Exception: UpdateException
2018-08-13T19:29:18+0300 G3Rit7sXEa [info] rollbackChanges("1")
2018-08-13T19:29:18+0300 G3Rit7sXEa [info] unlink .step
2018-08-13T19:29:18+0300 G3Rit7sXEa [info] end of rollbackChanges()
2018-08-13T19:59:21+0300 dP8WFugoHl [info] request to updater
2018-08-13T19:59:21+0300 dP8WFugoHl [info] currentStep()
2018-08-13T19:59:21+0300 dP8WFugoHl [info] POST request for step "1"
2018-08-13T19:59:21+0300 dP8WFugoHl [info] startStep("1")
2018-08-13T19:59:21+0300 dP8WFugoHl [info] checkForExpectedFilesAndFolders()
2018-08-13T19:59:21+0300 dP8WFugoHl [error] POST request failed with UpdateException
2018-08-13T19:59:21+0300 dP8WFugoHl [error] Exception: UpdateException
2018-08-13T19:59:21+0300 dP8WFugoHl [info] rollbackChanges("1")
2018-08-13T19:59:21+0300 dP8WFugoHl [info] unlink .step
2018-08-13T19:59:21+0300 dP8WFugoHl [info] end of rollbackChanges()
2018-08-13T20:00:03+0300 bDqdE7hJFH [info] request to updater
2018-08-13T20:00:03+0300 bDqdE7hJFH [info] currentStep()
2018-08-13T20:00:03+0300 bDqdE7hJFH [info] POST request for step "1"
2018-08-13T20:00:04+0300 bDqdE7hJFH [info] startStep("1")
2018-08-13T20:00:04+0300 bDqdE7hJFH [info] checkForExpectedFilesAndFolders()
2018-08-13T20:00:04+0300 bDqdE7hJFH [info] end of checkForExpectedFilesAndFolders()
2018-08-13T20:00:04+0300 bDqdE7hJFH [info] endStep("1")
2018-08-13T20:00:04+0300 45B8oapkad [info] request to updater
2018-08-13T20:00:04+0300 45B8oapkad [info] currentStep()
2018-08-13T20:00:04+0300 45B8oapkad [info] Step 1 is in state "end".
2018-08-13T20:00:04+0300 45B8oapkad [info] POST request for step "2"
2018-08-13T20:00:04+0300 45B8oapkad [info] startStep("2")
2018-08-13T20:00:04+0300 45B8oapkad [info] checkWritePermissions()
2018-08-13T20:00:04+0300 45B8oapkad [info] end of checkWritePermissions()
2018-08-13T20:00:04+0300 45B8oapkad [info] endStep("2")
2018-08-13T20:00:04+0300 jRga9KhCHW [info] request to updater
2018-08-13T20:00:04+0300 jRga9KhCHW [info] currentStep()
2018-08-13T20:00:04+0300 jRga9KhCHW [info] Step 2 is in state "end".
2018-08-13T20:00:04+0300 jRga9KhCHW [info] POST request for step "3"
2018-08-13T20:00:04+0300 jRga9KhCHW [info] startStep("3")
2018-08-13T20:00:04+0300 jRga9KhCHW [info] createBackup()
2018-08-13T20:00:30+0300 jRga9KhCHW [info] end of createBackup()
2018-08-13T20:00:30+0300 jRga9KhCHW [info] endStep("3")
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] request to updater
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] currentStep()
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] Step 3 is in state "end".
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] POST request for step "4"
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] startStep("4")
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] downloadUpdate()
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] getUpdateServerResponse()
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] updaterServer: https://updates.nextcloud.org/updater_server/
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] releaseChannel: beta
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] internal version: 14.0.0.13
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] updateURL: https://updates.nextcloud.org/updater_server/?version=14x0x0x13xxxbetaxx2018-07-26T11%3A34%3A24%2B00%3A00+adcc061166ab86bd39b004bd85847320fdd2d525x7x0x30
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] getUpdateServerResponse response: Array
2018-08-13T20:00:30+0300 MSzd6elnX0 [info] storage location exists
2018-08-13T20:00:34+0300 MSzd6elnX0 [info] end of downloadUpdate()
2018-08-13T20:00:34+0300 MSzd6elnX0 [info] endStep("4")
2018-08-13T20:00:34+0300 426GavRAQ1 [info] request to updater
2018-08-13T20:00:34+0300 426GavRAQ1 [info] currentStep()
2018-08-13T20:00:34+0300 426GavRAQ1 [info] Step 4 is in state "end".
2018-08-13T20:00:34+0300 426GavRAQ1 [info] POST request for step "5"
2018-08-13T20:00:34+0300 426GavRAQ1 [info] startStep("5")
2018-08-13T20:00:36+0300 426GavRAQ1 [info] verifyIntegrity()
2018-08-13T20:00:36+0300 426GavRAQ1 [info] getUpdateServerResponse()
2018-08-13T20:00:36+0300 426GavRAQ1 [info] updaterServer: https://updates.nextcloud.org/updater_server/
2018-08-13T20:00:36+0300 426GavRAQ1 [info] releaseChannel: beta
2018-08-13T20:00:36+0300 426GavRAQ1 [info] internal version: 14.0.0.13
2018-08-13T20:00:36+0300 426GavRAQ1 [info] updateURL: https://updates.nextcloud.org/updater_server/?version=14x0x0x13xxxbetaxx2018-07-26T11%3A34%3A24%2B00%3A00+adcc061166ab86bd39b004bd85847320fdd2d525x7x0x30
2018-08-13T20:00:36+0300 426GavRAQ1 [info] getUpdateServerResponse response: Array
2018-08-13T20:00:36+0300 426GavRAQ1 [info] storage location: /mnt/store/nextcloud//updater-oclqw2vahznw/downloads/
2018-08-13T20:00:38+0300 426GavRAQ1 [info] end of verifyIntegrity()
2018-08-13T20:00:38+0300 426GavRAQ1 [info] endStep("5")
2018-08-13T20:00:38+0300 N9S4j1OBDv [info] request to updater
2018-08-13T20:00:38+0300 N9S4j1OBDv [info] currentStep()
2018-08-13T20:00:38+0300 N9S4j1OBDv [info] Step 5 is in state "end".
2018-08-13T20:00:38+0300 N9S4j1OBDv [info] POST request for step "6"
2018-08-13T20:00:38+0300 N9S4j1OBDv [info] startStep("6")
2018-08-13T20:00:38+0300 N9S4j1OBDv [info] extractDownload()
2018-08-13T20:00:38+0300 N9S4j1OBDv [info] storage location: /mnt/store/nextcloud//updater-oclqw2vahznw/downloads/
2018-08-13T20:00:47+0300 N9S4j1OBDv [info] end of extractDownload()
2018-08-13T20:00:47+0300 N9S4j1OBDv [info] endStep("6")
2018-08-13T20:00:47+0300 xqo7EulZSj [info] request to updater
2018-08-13T20:00:47+0300 xqo7EulZSj [info] currentStep()
2018-08-13T20:00:47+0300 xqo7EulZSj [info] Step 6 is in state "end".
2018-08-13T20:00:47+0300 xqo7EulZSj [info] POST request for step "7"
2018-08-13T20:00:47+0300 xqo7EulZSj [info] startStep("7")
2018-08-13T20:00:48+0300 xqo7EulZSj [info] setMaintenanceMode("true")
2018-08-13T20:00:48+0300 xqo7EulZSj [info] configFileName /var/www/nextcloud/updater/../config/config.php
2018-08-13T20:00:48+0300 xqo7EulZSj [info] end of setMaintenanceMode()
2018-08-13T20:00:48+0300 xqo7EulZSj [info] endStep("7")
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] request to updater
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] currentStep()
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] Step 7 is in state "end".
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] POST request for step "8"
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] startStep("8")
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] replaceEntryPoints()
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] replace index.php
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] replace status.php
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] replace remote.php
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] replace public.php
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] replace ocs/v1.php
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] replace ocs/v2.php
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] end of replaceEntryPoints()
2018-08-13T20:00:48+0300 0Xl7EwDv6t [info] endStep("8")
2018-08-13T20:00:48+0300 wTDpXWrMy5 [info] request to updater
2018-08-13T20:00:48+0300 wTDpXWrMy5 [info] currentStep()
2018-08-13T20:00:48+0300 wTDpXWrMy5 [info] Step 8 is in state "end".
2018-08-13T20:00:48+0300 wTDpXWrMy5 [info] POST request for step "9"
2018-08-13T20:00:48+0300 wTDpXWrMy5 [info] startStep("9")
2018-08-13T20:00:48+0300 wTDpXWrMy5 [info] deleteOldFiles()
2018-08-13T20:00:49+0300 wTDpXWrMy5 [info] config sample exists
2018-08-13T20:00:49+0300 wTDpXWrMy5 [info] themes README exists
2018-08-13T20:00:50+0300 wTDpXWrMy5 [info] end of deleteOldFiles()
2018-08-13T20:00:50+0300 wTDpXWrMy5 [info] endStep("9")
2018-08-13T20:00:50+0300 h5wGcabxa4 [info] request to updater
2018-08-13T20:00:50+0300 h5wGcabxa4 [info] currentStep()
2018-08-13T20:00:50+0300 h5wGcabxa4 [info] Step 9 is in state "end".
2018-08-13T20:00:50+0300 h5wGcabxa4 [info] POST request for step "10"
2018-08-13T20:00:50+0300 h5wGcabxa4 [info] startStep("10")
2018-08-13T20:00:50+0300 h5wGcabxa4 [info] moveNewVersionInPlace()
2018-08-13T20:00:50+0300 h5wGcabxa4 [info] storage location: /mnt/store/nextcloud//updater-oclqw2vahznw/downloads/nextcloud/
2018-08-13T20:01:28+0300 h5wGcabxa4 [info] end of moveNewVersionInPlace()
2018-08-13T20:01:28+0300 h5wGcabxa4 [info] endStep("10")
2018-08-13T20:01:33+0300 vAWUPVlxbK [info] request to updater
2018-08-13T20:02:03+0300 vAWUPVlxbK [info] currentStep()
2018-08-13T20:02:03+0300 vAWUPVlxbK [info] Step 10 is in state "end".
2018-08-13T20:02:03+0300 vAWUPVlxbK [info] POST request for step "11"
2018-08-13T20:02:04+0300 vAWUPVlxbK [info] startStep("11")
2018-08-13T20:02:04+0300 vAWUPVlxbK [info] setMaintenanceMode("false")
2018-08-13T20:02:04+0300 vAWUPVlxbK [info] configFileName /var/www/nextcloud/updater/../config/config.php
2018-08-13T20:02:04+0300 vAWUPVlxbK [info] end of setMaintenanceMode()
2018-08-13T20:02:04+0300 vAWUPVlxbK [info] endStep("11")
2018-08-13T20:02:04+0300 78zUu57a0T [info] request to updater
2018-08-13T20:02:04+0300 78zUu57a0T [info] currentStep()
2018-08-13T20:02:04+0300 78zUu57a0T [info] Step 11 is in state "end".
2018-08-13T20:02:04+0300 78zUu57a0T [info] POST request for step "12"
2018-08-13T20:02:04+0300 78zUu57a0T [info] startStep("12")
2018-08-13T20:02:04+0300 78zUu57a0T [info] finalize()
2018-08-13T20:02:04+0300 78zUu57a0T [info] storage location: /mnt/store/nextcloud//updater-oclqw2vahznw/downloads/nextcloud/
2018-08-13T20:02:05+0300 78zUu57a0T [info] call opcache_reset()
2018-08-13T20:02:05+0300 78zUu57a0T [info] end of finalize()
2018-08-13T20:02:05+0300 78zUu57a0T [info] endStep("12")
root@localhost:~

@MorrisJobke
Copy link
Member

Here's an extract for the day of the update from my updater.log, where some [error] POST request failed with UpdateException entries were logged several times.

The log looks good. The errors are in the step where it checks for files and permissions - so this is all fine.

@Matei-Ciobotaru
Copy link
Author

I've also checked the updater.log of the previous update, from 13.0.5 to 14.0.0 and, and no error entries were logged.

On the other hand, I still have the ocm-provider directory in NC 14.0.0 beta 3.
I will try to update to 14.0.0 beta 4 via the web UI to see if I get the same error, is there any setting I should apply beforehand to increase verbosity in the updater.log ?

: ~$ ls -ldrth /var/www/nextcloud/ocm-provider/
drwxr-xr-x 2 www-data www-data 4.0K Aug 13 20:01 /var/www/nextcloud/ocm-provider/
: ~$ ls -lrth /var/www/nextcloud/ocm-provider/
total 4.0K
-rw-r--r-- 1 www-data www-data 1.4K Aug 13 20:01 index.php

@MorrisJobke
Copy link
Member

On the other hand, I still have the ocm-provider directory in NC 14.0.0 beta 3.

This is totally fine, because it needs to be there ;)

is there any setting I should apply beforehand to increase verbosity in the updater.log ?

It logs already everything. ;)

@Matei-Ciobotaru
Copy link
Author

I have successfully updated to v14.0.0 beta 4 via the web UI and no error was reported.
Nevertheless, I've attached the new updater.log entries here so you may compare these with the previous update log.

I made some preliminary tests after the update to beta 4 and so far so good. :)

2018-08-22T17:43:20+0300 QOaUt2OvaH [info] request to updater
2018-08-22T17:43:20+0300 QOaUt2OvaH [info] currentStep()
2018-08-22T17:43:20+0300 QOaUt2OvaH [info] show HTML page
2018-08-22T17:43:20+0300 QOaUt2OvaH [info] current version: 14.0.0 Beta 3 build time: 2018-08-09T09:35:30+00:00 def2bf2
2018-08-22T17:43:20+0300 QOaUt2OvaH [info] getUpdateServerResponse()
2018-08-22T17:43:20+0300 QOaUt2OvaH [info] updaterServer: https://updates.nextcloud.org/updater_server/
2018-08-22T17:43:20+0300 QOaUt2OvaH [info] releaseChannel: beta
2018-08-22T17:43:20+0300 QOaUt2OvaH [info] internal version: 14.0.0.15
2018-08-22T17:43:20+0300 QOaUt2OvaH [info] updateURL: https://updates.nextcloud.org/updater_server/?version=14x0x0x15xxxbetaxx2018-08-09T09%3A35%3A30%2B00%3A00+def2bf2086b00b5bb00b362a5a50840fdf8b4cc0x7x0x30
2018-08-22T17:43:20+0300 QOaUt2OvaH [info] getUpdateServerResponse response: Array
2018-08-22T17:43:20+0300 QOaUt2OvaH [info] checkForUpdate() Array
2018-08-22T17:43:20+0300 QOaUt2OvaH [info] end of checkForUpdate() Update to Nextcloud 14.0.0 Beta 4 available. (channel: "beta")
Following file will be downloaded automatically: https://download.nextcloud.com/server/prereleases/nextcloud-14.0.0beta4.zip
2018-08-22T17:43:28+0300 MHP5SJpJwX [info] request to updater
2018-08-22T17:43:28+0300 MHP5SJpJwX [info] currentStep()
2018-08-22T17:43:28+0300 MHP5SJpJwX [info] POST request for step "1"
2018-08-22T17:43:28+0300 MHP5SJpJwX [info] startStep("1")
2018-08-22T17:43:28+0300 MHP5SJpJwX [info] checkForExpectedFilesAndFolders()
2018-08-22T17:43:28+0300 MHP5SJpJwX [info] end of checkForExpectedFilesAndFolders()
2018-08-22T17:43:28+0300 MHP5SJpJwX [info] endStep("1")
2018-08-22T17:43:28+0300 GoiRdORjRf [info] request to updater
2018-08-22T17:43:28+0300 GoiRdORjRf [info] currentStep()
2018-08-22T17:43:28+0300 GoiRdORjRf [info] Step 1 is in state "end".
2018-08-22T17:43:28+0300 GoiRdORjRf [info] POST request for step "2"
2018-08-22T17:43:28+0300 GoiRdORjRf [info] startStep("2")
2018-08-22T17:43:28+0300 GoiRdORjRf [info] checkWritePermissions()
2018-08-22T17:43:28+0300 GoiRdORjRf [info] end of checkWritePermissions()
2018-08-22T17:43:28+0300 GoiRdORjRf [info] endStep("2")
2018-08-22T17:43:28+0300 j3IC1o7FLy [info] request to updater
2018-08-22T17:43:28+0300 j3IC1o7FLy [info] currentStep()
2018-08-22T17:43:28+0300 j3IC1o7FLy [info] Step 2 is in state "end".
2018-08-22T17:43:28+0300 j3IC1o7FLy [info] POST request for step "3"
2018-08-22T17:43:29+0300 j3IC1o7FLy [info] startStep("3")
2018-08-22T17:43:29+0300 j3IC1o7FLy [info] createBackup()
2018-08-22T17:43:57+0300 j3IC1o7FLy [info] end of createBackup()
2018-08-22T17:43:57+0300 j3IC1o7FLy [info] endStep("3")
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] request to updater
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] currentStep()
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] Step 3 is in state "end".
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] POST request for step "4"
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] startStep("4")
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] downloadUpdate()
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] getUpdateServerResponse()
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] updaterServer: https://updates.nextcloud.org/updater_server/
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] releaseChannel: beta
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] internal version: 14.0.0.15
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] updateURL: https://updates.nextcloud.org/updater_server/?version=14x0x0x15xxxbetaxx2018-08-09T09%3A35%3A30%2B00%3A00+def2bf2086b00b5bb00b362a5a50840fdf8b4cc0x7x0x30
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] getUpdateServerResponse response: Array
2018-08-22T17:43:57+0300 ayfZE8I3Sf [info] storage location exists
2018-08-22T17:44:01+0300 ayfZE8I3Sf [info] end of downloadUpdate()
2018-08-22T17:44:01+0300 ayfZE8I3Sf [info] endStep("4")
2018-08-22T17:44:01+0300 1yEkqS8hbZ [info] request to updater
2018-08-22T17:44:01+0300 1yEkqS8hbZ [info] currentStep()
2018-08-22T17:44:01+0300 1yEkqS8hbZ [info] Step 4 is in state "end".
2018-08-22T17:44:01+0300 1yEkqS8hbZ [info] POST request for step "5"
2018-08-22T17:44:02+0300 1yEkqS8hbZ [info] startStep("5")
2018-08-22T17:44:02+0300 1yEkqS8hbZ [info] verifyIntegrity()
2018-08-22T17:44:02+0300 1yEkqS8hbZ [info] getUpdateServerResponse()
2018-08-22T17:44:02+0300 1yEkqS8hbZ [info] updaterServer: https://updates.nextcloud.org/updater_server/
2018-08-22T17:44:02+0300 1yEkqS8hbZ [info] releaseChannel: beta
2018-08-22T17:44:02+0300 1yEkqS8hbZ [info] internal version: 14.0.0.15
2018-08-22T17:44:02+0300 1yEkqS8hbZ [info] updateURL: https://updates.nextcloud.org/updater_server/?version=14x0x0x15xxxbetaxx2018-08-09T09%3A35%3A30%2B00%3A00+def2bf2086b00b5bb00b362a5a50840fdf8b4cc0x7x0x30
2018-08-22T17:44:02+0300 1yEkqS8hbZ [info] getUpdateServerResponse response: Array
2018-08-22T17:44:02+0300 1yEkqS8hbZ [info] storage location: /mnt/store/nextcloud//updater-oclqw2vahznw/downloads/
2018-08-22T17:44:04+0300 1yEkqS8hbZ [info] end of verifyIntegrity()
2018-08-22T17:44:04+0300 1yEkqS8hbZ [info] endStep("5")
2018-08-22T17:44:04+0300 xu3f74DfKp [info] request to updater
2018-08-22T17:44:04+0300 xu3f74DfKp [info] currentStep()
2018-08-22T17:44:04+0300 xu3f74DfKp [info] Step 5 is in state "end".
2018-08-22T17:44:04+0300 xu3f74DfKp [info] POST request for step "6"
2018-08-22T17:44:04+0300 xu3f74DfKp [info] startStep("6")
2018-08-22T17:44:04+0300 xu3f74DfKp [info] extractDownload()
2018-08-22T17:44:04+0300 xu3f74DfKp [info] storage location: /mnt/store/nextcloud//updater-oclqw2vahznw/downloads/
2018-08-22T17:44:17+0300 xu3f74DfKp [info] end of extractDownload()
2018-08-22T17:44:17+0300 xu3f74DfKp [info] endStep("6")
2018-08-22T17:44:17+0300 NVX3UBbDF3 [info] request to updater
2018-08-22T17:44:17+0300 NVX3UBbDF3 [info] currentStep()
2018-08-22T17:44:17+0300 NVX3UBbDF3 [info] Step 6 is in state "end".
2018-08-22T17:44:17+0300 NVX3UBbDF3 [info] POST request for step "7"
2018-08-22T17:44:18+0300 NVX3UBbDF3 [info] startStep("7")
2018-08-22T17:44:18+0300 NVX3UBbDF3 [info] setMaintenanceMode("true")
2018-08-22T17:44:18+0300 NVX3UBbDF3 [info] configFileName /var/www/nextcloud/updater/../config/config.php
2018-08-22T17:44:18+0300 NVX3UBbDF3 [info] end of setMaintenanceMode()
2018-08-22T17:44:18+0300 NVX3UBbDF3 [info] endStep("7")
2018-08-22T17:44:18+0300 SGCw02o9jz [info] request to updater
2018-08-22T17:44:18+0300 SGCw02o9jz [info] currentStep()
2018-08-22T17:44:18+0300 SGCw02o9jz [info] Step 7 is in state "end".
2018-08-22T17:44:18+0300 SGCw02o9jz [info] POST request for step "8"
2018-08-22T17:44:18+0300 SGCw02o9jz [info] startStep("8")
2018-08-22T17:44:18+0300 SGCw02o9jz [info] replaceEntryPoints()
2018-08-22T17:44:18+0300 SGCw02o9jz [info] replace index.php
2018-08-22T17:44:18+0300 SGCw02o9jz [info] replace status.php
2018-08-22T17:44:18+0300 SGCw02o9jz [info] replace remote.php
2018-08-22T17:44:18+0300 SGCw02o9jz [info] replace public.php
2018-08-22T17:44:18+0300 SGCw02o9jz [info] replace ocs/v1.php
2018-08-22T17:44:18+0300 SGCw02o9jz [info] replace ocs/v2.php
2018-08-22T17:44:18+0300 SGCw02o9jz [info] end of replaceEntryPoints()
2018-08-22T17:44:18+0300 SGCw02o9jz [info] endStep("8")
2018-08-22T17:44:18+0300 8Q4b5cgJr1 [info] request to updater
2018-08-22T17:44:18+0300 8Q4b5cgJr1 [info] currentStep()
2018-08-22T17:44:18+0300 8Q4b5cgJr1 [info] Step 8 is in state "end".
2018-08-22T17:44:18+0300 8Q4b5cgJr1 [info] POST request for step "9"
2018-08-22T17:44:18+0300 8Q4b5cgJr1 [info] startStep("9")
2018-08-22T17:44:18+0300 8Q4b5cgJr1 [info] deleteOldFiles()
2018-08-22T17:44:20+0300 8Q4b5cgJr1 [info] config sample exists
2018-08-22T17:44:20+0300 8Q4b5cgJr1 [info] themes README exists
2018-08-22T17:44:21+0300 8Q4b5cgJr1 [info] end of deleteOldFiles()
2018-08-22T17:44:21+0300 8Q4b5cgJr1 [info] endStep("9")
2018-08-22T17:44:21+0300 8eW6hRHtum [info] request to updater
2018-08-22T17:44:21+0300 8eW6hRHtum [info] currentStep()
2018-08-22T17:44:21+0300 8eW6hRHtum [info] Step 9 is in state "end".
2018-08-22T17:44:21+0300 8eW6hRHtum [info] POST request for step "10"
2018-08-22T17:44:21+0300 8eW6hRHtum [info] startStep("10")
2018-08-22T17:44:23+0300 8eW6hRHtum [info] moveNewVersionInPlace()
2018-08-22T17:44:23+0300 8eW6hRHtum [info] storage location: /mnt/store/nextcloud//updater-oclqw2vahznw/downloads/nextcloud/
2018-08-22T17:44:46+0300 8eW6hRHtum [info] end of moveNewVersionInPlace()
2018-08-22T17:44:46+0300 8eW6hRHtum [info] endStep("10")
2018-08-22T17:44:54+0300 ec5U1PNsoi [info] request to updater
2018-08-22T17:44:54+0300 ec5U1PNsoi [info] currentStep()
2018-08-22T17:44:54+0300 ec5U1PNsoi [info] Step 10 is in state "end".
2018-08-22T17:44:54+0300 ec5U1PNsoi [info] POST request for step "11"
2018-08-22T17:44:55+0300 ec5U1PNsoi [info] startStep("11")
2018-08-22T17:44:55+0300 ec5U1PNsoi [info] setMaintenanceMode("false")
2018-08-22T17:44:55+0300 ec5U1PNsoi [info] configFileName /var/www/nextcloud/updater/../config/config.php
2018-08-22T17:44:56+0300 ec5U1PNsoi [info] end of setMaintenanceMode()
2018-08-22T17:44:56+0300 ec5U1PNsoi [info] endStep("11")
2018-08-22T17:44:57+0300 t575XXLehT [info] request to updater
2018-08-22T17:44:57+0300 t575XXLehT [info] currentStep()
2018-08-22T17:44:57+0300 t575XXLehT [info] Step 11 is in state "end".
2018-08-22T17:44:57+0300 t575XXLehT [info] POST request for step "12"
2018-08-22T17:44:57+0300 t575XXLehT [info] startStep("12")
2018-08-22T17:44:57+0300 t575XXLehT [info] finalize()
2018-08-22T17:44:57+0300 t575XXLehT [info] storage location: /mnt/store/nextcloud//updater-oclqw2vahznw/downloads/nextcloud/
2018-08-22T17:45:03+0300 t575XXLehT [info] call opcache_reset()
2018-08-22T17:45:03+0300 t575XXLehT [info] end of finalize()
2018-08-22T17:45:03+0300 t575XXLehT [info] endStep("12")

@MorrisJobke
Copy link
Member

Nevertheless, I've attached the new updater.log entries here so you may compare these with the previous update log.

I made some preliminary tests after the update to beta 4 and so far so good. :)

Also the log looks good 👍

@MorrisJobke
Copy link
Member

The underlying problem: The updater replaced everything but the updater/index.php and thus causes this. Replacing it by the one in https://github.com/nextcloud/updater/blob/master/index.php should fix the problem for now. We look into why the replacement failed in the first place but couldn't reproduce locally. So if this happens again to anybody then we would like to debug this.

Thanks for the feedback.

@janvlug
Copy link

janvlug commented Oct 12, 2018

I see this issue in the update from 14.0.2 to 14.0.3:
screenshot from 2018-10-12 11-26-30

@kesselb
Copy link
Contributor

kesselb commented Oct 12, 2018

@janvlug you tried #10677 (comment)?

@janvlug
Copy link

janvlug commented Oct 15, 2018

@danielkesselberg No not yet. Actually I waited a bit with trying to fix this, because maybe @MorrisJobke is interested in debugging.
Should I open a new issue, or can this one be re-opened?

@ChristophWurst
Copy link
Member

Reopened to get more debug info.

@MorrisJobke
Copy link
Member

@danielkesselberg No not yet. Actually I waited a bit with trying to fix this, because maybe @MorrisJobke is interested in debugging.

I seriously have no idea why the old updater is not properly replaced. Could you check the old updater.log in your data directory? Maybe it has some more information. :(

@janvlug
Copy link

janvlug commented Oct 25, 2018

Sorry for the late reply. Here is a snippet from the updater.log:

2018-10-15T17:39:05+0200 eOKFDxUpZn [info] end of checkForUpdate() Update to Nextcloud 14.0.3 available. (channel: "stable")
Following file will be downloaded automatically: https://download.nextcloud.com/server/releases/nextcloud-14.0.3.zip 2018-10-15T17:39:11+0200 j4syPCnSub [info] request to updater 2018-10-15T17:39:11+0200 j4syPCnSub [info] currentStep() 2018-10-15T17:39:11+0200 j4syPCnSub [info] POST request for step "1" 2018-10-15T17:39:11+0200 j4syPCnSub [info] startStep("1") 2018-10-15T17:39:11+0200 j4syPCnSub [info] checkForExpectedFilesAndFolders() 2018-10-15T17:39:11+0200 j4syPCnSub [error] POST request failed with UpdateException 2018-10-15T17:39:11+0200 j4syPCnSub [error] Exception: UpdateException Message: Code:0 Trace: #0 /volume1/web/nextcloud/updater/index.php(1285): Updater->checkForExpectedFilesAndFolders() #1 {main} File:/volume1/web/nextcloud/updater/index.php Line:383 Data: Array ( [0] => ocm-provider ) 2018-10-15T17:39:11+0200 j4syPCnSub [info] rollbackChanges("1") 2018-10-15T17:39:11+0200 j4syPCnSub [info] unlink .step 2018-10-15T17:39:11+0200 j4syPCnSub [info] end of rollbackChanges()

Here is the only file (index.php) that resides in the directory ocm-provider (I zipped it to be able to upload it here):
index.php.zip

@kesselb
Copy link
Contributor

kesselb commented Oct 25, 2018

Could you upload updater\index.php? Could you also share ls -al updater?

@janvlug
Copy link

janvlug commented Oct 25, 2018

updater/index.php.zip

ls -al ./web/nextcloud/updater/index.php
-rw-r--r-- 1 http http 62109 Aug 30 19:02 ./web/nextcloud/updater/index.php

@MorrisJobke
Copy link
Member

Big question also is, why the signature check does not trigger the code integrity problem. 🤔

@janvlug
Copy link

janvlug commented Oct 29, 2018

Is there anything you would like me to test? Otherwise I will try to do the update after a few days by following the suggested workarounds.

@janvlug
Copy link

janvlug commented Nov 2, 2018

I removed the extra directory as suggested here: #10677 (comment).
The web update then went through without any problems.

@MorrisJobke
Copy link
Member

I removed the extra directory as suggested here: #10677 (comment).
The web update then went through without any problems.

The better approach is to use the correct version of the updater from https://github.com/nextcloud/updater/tree/stable14 (just download index.php and updater.phar and place it in the updater/ folder).

Then the update should work fine. No idea why the updater was not properly replaced at all.

@janvlug
Copy link

janvlug commented Nov 2, 2018

The better approach is to use the correct version of the updater

I already went for the removal of the extra directory and file.
After the update, the directory and file are installed again:

# ls -sla /volume1/web/nextcloud/ocm-provider
total 12
4 drwxr-xr-x  2 http http 4096 Nov  2 12:07 .
4 drwxr-x--- 14 http http 4096 Nov  2 12:07 ..
4 -rw-r--r--  1 http http 1332 Nov  2 12:02 index.php

@MorrisJobke
Copy link
Member

I already went for the removal of the extra directory and file.
After the update, the directory and file are installed again:

Because those files are actually code that we need. Thus the removal is not good. It's basically killing a feature. The problem here was the outdated updater script that was wrong in saying "extra directory ocm-provider" which is a totally valid directory in Nextcloud 14.

@mapleshadow
Copy link

I removed the extra directory as suggested here: #10677 (comment).
The web update then went through without any problems.

The better approach is to use the correct version of the updater from https://github.com/nextcloud/updater/tree/stable14 (just download index.php and updater.phar and place it in the updater/ folder).

Then the update should work fine. No idea why the updater was not properly replaced at all.

I also encountered this problem, according to your method, I solved the problem perfectly, thank you!

@omysurya
Copy link

Very simple.. for fixed this issue.
Please goto to root folder on your next-cloud / ownCloud.
~ # sudo rm -rf /var/www/next-cloud/ocm-provider
or
~# sudo mv /var/www/next-cloud/ocm-provider /tmp/

@MorrisJobke
Copy link
Member

Very simple.. for fixed this issue.
Please goto to root folder on your next-cloud / ownCloud.
~ # sudo rm -rf /var/www/next-cloud/ocm-provider
or
~# sudo mv /var/www/next-cloud/ocm-provider /tmp/

Please don't do this, but instead re-download the updater, because it somehow got not updated correctly :/

See #10677 (comment)

@mstancombe
Copy link

thanks @MorrisJobke
For anyone who is feeling lazy, here's the exact commands I used (am using NextCloudPi, nextcloud folder might be diff depending on how it's installed, also sudo)

cd /var/www/nextcloud/updater
cp index.php index.php.bak
cp updater.phar updater.phar.bak
rm index.php
rm updater.phar
wget https://raw.githubusercontent.com/nextcloud/updater/stable14/index.php
wget https://raw.githubusercontent.com/nextcloud/updater/stable14/updater.phar
chown www-data:www-data index.php
chown www-data:www-data updater.phar

After that the installer worked as expected.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

10 participants