Server: OnlyOffice files are randomly rolled back (and corrupted?)

Created on 29 Jun 2020  路  5Comments  路  Source: nextcloud/server

How to use GitHub

  • Please use the 馃憤 reaction to show that you are affected by the same issue.
  • Please don't comment if you have no relevant information to add. It's just extra noise for everyone subscribed to this issue.
  • Subscribe to receive notifications on status change and new comments.

Steps to reproduce

  1. Create an OnlyOffice file (currently happened twice, with .ods and .docx)
  2. Edit it occasionally via OnlyOffice web app and remember last edit date and last changes

Expected behaviour

Edits and last edit date are kept

Actual behaviour

File randomly rolls back to some older version, probably previous one, or rolls back and gets corrupted

Server configuration

Operating system: Linux 5.3.0-1028-aws # 30~18.04.1-Ubuntu SMP Mon Jun 22 15:48:21 UTC 2020 x86_64

Webserver: nginx/1.14.0 (fpm-fcgi)

Database: mysql 10.1.44

PHP version:

7.2.24-0ubuntu0.18.04.6
Modules loaded: Core, date, libxml, openssl, pcre, zlib, filter, hash, Reflection, SPL, sodium, session, standard, cgi-fcgi, mysqlnd, PDO, xml, bcmath, bz2, calendar, ctype, curl, dba, dom, mbstring, fileinfo, ftp, gd, gettext, iconv, imagick, intl, json, exif, mysqli, pdo_mysql, Phar, posix, readline, shmop, SimpleXML, soap, sockets, sysvmsg, sysvsem, sysvshm, tokenizer, wddx, xmlreader, xmlwriter, xsl, zip, Zend OPcache

Nextcloud version: 18.0.6 - 18.0.6.0

Updated from an older Nextcloud/ownCloud or fresh install:
updated from an older Nextcloud
Where did you install Nextcloud from: official website

Signing status

Array
(
)

List of activated apps

Enabled:
 - accessibility: 1.4.0
 - activity: 2.11.0
 - cloud_federation_api: 1.1.0
 - comments: 1.8.0
 - contacts: 3.3.0
 - dav: 1.14.0
 - documentserver_community: 0.1.6
 - extract: 1.2.4
 - federatedfilesharing: 1.8.0
 - federation: 1.8.0
 - files: 1.13.1
 - files_pdfviewer: 1.7.0
 - files_rightclick: 0.15.2
 - files_sharing: 1.10.1
 - files_trashbin: 1.8.0
 - files_versions: 1.11.0
 - files_videoplayer: 1.7.0
 - firstrunwizard: 2.7.0
 - issuetemplate: 0.6.0
 - logreader: 2.3.0
 - lookup_server_connector: 1.6.0
 - nextcloud_announcements: 1.7.0
 - notifications: 2.6.0
 - oauth2: 1.6.0
 - onlyoffice: 4.2.0
 - password_policy: 1.8.0
 - photos: 1.0.0
 - privacy: 1.2.0
 - provisioning_api: 1.8.0
 - recommendations: 0.6.0
 - serverinfo: 1.8.0
 - settings: 1.0.0
 - sharebymail: 1.8.0
 - support: 1.1.1
 - survey_client: 1.6.0
 - systemtags: 1.8.0
 - text: 2.0.0
 - theming: 1.9.0
 - theming_customcss: 1.6.0
 - twofactor_backupcodes: 1.7.0
 - updatenotification: 1.8.0
 - viewer: 1.2.0
 - workflowengine: 2.0.0
Disabled:
 - admin_audit
 - encryption
 - files_external
 - user_ldap

Configuration (config/config.php)

{
    "instanceid": "***REMOVED SENSITIVE VALUE***",
    "passwordsalt": "***REMOVED SENSITIVE VALUE***",
    "secret": "***REMOVED SENSITIVE VALUE***",
    "trusted_domains": [
        "nxmail.org"
    ],
    "datadirectory": "***REMOVED SENSITIVE VALUE***",
    "dbtype": "mysql",
    "version": "18.0.6.0",
    "overwrite.cli.url": "https:\/\/nxmail.org",
    "dbname": "***REMOVED SENSITIVE VALUE***",
    "dbhost": "***REMOVED SENSITIVE VALUE***",
    "dbport": "",
    "dbtableprefix": "oc_",
    "dbuser": "***REMOVED SENSITIVE VALUE***",
    "dbpassword": "***REMOVED SENSITIVE VALUE***",
    "installed": true,
    "mail_smtpmode": "smtp",
    "mail_smtpsecure": "ssl",
    "mail_sendmailmode": "smtp",
    "mail_from_address": "***REMOVED SENSITIVE VALUE***",
    "mail_domain": "***REMOVED SENSITIVE VALUE***",
    "mail_smtpauthtype": "LOGIN",
    "mail_smtpauth": 1,
    "mail_smtphost": "***REMOVED SENSITIVE VALUE***",
    "mail_smtpport": "465",
    "mail_smtpname": "***REMOVED SENSITIVE VALUE***",
    "mail_smtppassword": "***REMOVED SENSITIVE VALUE***",
    "twofactor_enforced": "false",
    "twofactor_enforced_groups": [],
    "twofactor_enforced_excluded_groups": [],
    "maintenance": false,
    "loglevel": 2,
    "has_rebuilt_cache": true,
    "theme": "",
    "mysql.utf8mb4": true,
    "updater.release.channel": "stable"
}

Are you using external storage, if yes which one: local/smb/sftp/...
no
Are you using encryption:
no
Are you using an external user-backend, if yes which one: LDAP/ActiveDirectory/Webdav/...
no

Client configuration

Browser: Mozilla/5.0 (Windows NT 10.0; rv:68.0) Gecko/20100101 Firefox/68.0

Operating system: Debian Testing

Logs

Web server error log

PHP

[21-Jun-2020 06:25:02] NOTICE: error log file re-opened
[21-Jun-2020 16:31:06] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[21-Jun-2020 16:31:21] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[21-Jun-2020 16:33:53] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[21-Jun-2020 17:02:45] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[21-Jun-2020 19:01:11] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[21-Jun-2020 19:36:52] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[21-Jun-2020 19:37:13] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[22-Jun-2020 12:27:53] NOTICE: Terminating ...
[22-Jun-2020 12:27:53] NOTICE: exiting, bye-bye!
[22-Jun-2020 12:28:43] NOTICE: fpm is running, pid 866
[22-Jun-2020 12:28:43] NOTICE: ready to handle connections
[22-Jun-2020 12:28:43] NOTICE: systemd monitor interval set to 10000ms
[22-Jun-2020 22:52:12] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[23-Jun-2020 16:34:51] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[24-Jun-2020 21:11:45] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[25-Jun-2020 08:50:11] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[25-Jun-2020 20:30:46] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[25-Jun-2020 20:31:07] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[25-Jun-2020 20:31:12] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[25-Jun-2020 20:41:59] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[25-Jun-2020 20:42:21] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[25-Jun-2020 21:46:53] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[25-Jun-2020 21:48:55] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[25-Jun-2020 21:49:48] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[27-Jun-2020 09:26:08] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[28-Jun-2020 11:37:45] NOTICE: Terminating ...
[28-Jun-2020 11:37:45] NOTICE: exiting, bye-bye!
[28-Jun-2020 11:38:34] NOTICE: fpm is running, pid 858
[28-Jun-2020 11:38:34] NOTICE: ready to handle connections
[28-Jun-2020 11:38:34] NOTICE: systemd monitor interval set to 10000ms
[29-Jun-2020 06:25:02] NOTICE: error log file re-opened
[29-Jun-2020 18:25:41] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[29-Jun-2020 18:25:50] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[29-Jun-2020 19:07:50] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[29-Jun-2020 19:13:44] WARNING: [pool www] server reached pm.max_children setting (5), consider raising it
[29-Jun-2020 19:15:32] NOTICE: Terminating ...
[29-Jun-2020 19:15:32] NOTICE: exiting, bye-bye!
[29-Jun-2020 19:16:23] NOTICE: fpm is running, pid 871
[29-Jun-2020 19:16:23] NOTICE: ready to handle connections
[29-Jun-2020 19:16:23] NOTICE: systemd monitor interval set to 10000ms

Nginx

2020/06/29 19:14:21 [error] 948#948: *3175 access forbidden by rule, client: 84.23.51.135, server: nxmail.org, request: "GET /data/.ocdata?t=1593447261100 HTTP/1.1", host: "nxmail.org"
2020/06/29 19:15:32 [error] 948#948: *3022 recv() failed (104: Connection reset by peer) while reading response header from upstream, client: 84.23.51.135, server: nxmail.org, request: "POST /apps/documentserver_community/3rdparty/onlyoffice/documentserver/doc/2915800199/c/621/1qxgq2qu/xhr?t=1593447332167 HTTP/1.1", upstream: "fastcgi://unix:/var/run/php/php7.2-fpm.sock:", host: "nxmail.org"

Nextcloud log

{"reqId":"6evle2qyzsa0mn9h76sq","level":3,"time":"2020-06-29T17:01:54+00:00","remoteAddr":"84.23.51.135","user":"redacted","app":"PHP","method":"POST","url":"/apps/issuetemplate/render","message":"Undefined index: client-detail at /var/www/html/apps/issuetemplate/lib/Detail.php#59","userAgent":"Mozilla/5.0 (Windows NT 10.0; rv:68.0) Gecko/20100101 Firefox/68.0","version":"18.0.6.0"}
{"reqId":"6evle2qyzsa0mn9h76sq","level":3,"time":"2020-06-29T17:01:54+00:00","remoteAddr":"84.23.51.135","user":"redacted","app":"PHP","method":"POST","url":"/apps/issuetemplate/render","message":"Undefined index: client-detail at /var/www/html/apps/issuetemplate/lib/Detail.php#59","userAgent":"Mozilla/5.0 (Windows NT 10.0; rv:68.0) Gecko/20100101 Firefox/68.0","version":"18.0.6.0"}
{"reqId":"6evle2qyzsa0mn9h76sq","level":3,"time":"2020-06-29T17:01:54+00:00","remoteAddr":"84.23.51.135","user":"redacted","app":"PHP","method":"POST","url":"/apps/issuetemplate/render","message":"Undefined index: log-detail at /var/www/html/apps/issuetemplate/lib/Detail.php#59","userAgent":"Mozilla/5.0 (Windows NT 10.0; rv:68.0) Gecko/20100101 Firefox/68.0","version":"18.0.6.0"}
{"reqId":"6evle2qyzsa0mn9h76sq","level":3,"time":"2020-06-29T17:01:54+00:00","remoteAddr":"84.23.51.135","user":"redacted","app":"PHP","method":"POST","url":"/apps/issuetemplate/render","message":"Undefined index: log-detail at /var/www/html/apps/issuetemplate/lib/Detail.php#59","userAgent":"Mozilla/5.0 (Windows NT 10.0; rv:68.0) Gecko/20100101 Firefox/68.0","version":"18.0.6.0"}
{"reqId":"6evle2qyzsa0mn9h76sq","level":3,"time":"2020-06-29T17:01:54+00:00","remoteAddr":"84.23.51.135","user":"redacted","app":"PHP","method":"POST","url":"/apps/issuetemplate/render","message":"Undefined index: log-detail at /var/www/html/apps/issuetemplate/lib/Detail.php#59","userAgent":"Mozilla/5.0 (Windows NT 10.0; rv:68.0) Gecko/20100101 Firefox/68.0","version":"18.0.6.0"}
{"reqId":"5HlNpiQuFKjFzISC0cV1","level":3,"time":"2020-06-29T17:02:17+00:00","remoteAddr":"84.23.51.135","user":"redacted","app":"PHP","method":"POST","url":"/apps/issuetemplate/render","message":"Undefined index: log-detail at /var/www/html/apps/issuetemplate/lib/Detail.php#59","userAgent":"Mozilla/5.0 (Windows NT 10.0; rv:68.0) Gecko/20100101 Firefox/68.0","version":"18.0.6.0"}
{"reqId":"5HlNpiQuFKjFzISC0cV1","level":3,"time":"2020-06-29T17:02:17+00:00","remoteAddr":"84.23.51.135","user":"redacted","app":"PHP","method":"POST","url":"/apps/issuetemplate/render","message":"Undefined index: log-detail at /var/www/html/apps/issuetemplate/lib/Detail.php#59","userAgent":"Mozilla/5.0 (Windows NT 10.0; rv:68.0) Gecko/20100101 Firefox/68.0","version":"18.0.6.0"}
{"reqId":"5HlNpiQuFKjFzISC0cV1","level":3,"time":"2020-06-29T17:02:17+00:00","remoteAddr":"84.23.51.135","user":"redacted","app":"PHP","method":"POST","url":"/apps/issuetemplate/render","message":"Undefined index: log-detail at /var/www/html/apps/issuetemplate/lib/Detail.php#59","userAgent":"Mozilla/5.0 (Windows NT 10.0; rv:68.0) Gecko/20100101 Firefox/68.0","version":"18.0.6.0"}

Attached is the corrupted file with last edit date of 2020-06-19, actual last edit date is 2020-06-29.
Also, I am no longer able to open this file in OnlyOffice web app, it's probably corrupted.
As I can see, it is also empty.
1.docx

0. Needs triage bug
Source
picture omicron-b

All 5 comments

Nginx error 104: Connection reset by peer was probably caused by me rebooting the server when I could not open the file.

omicron-b picture omicron-b on 29 Jun 2020

hmm, wrong report
last edit date is not actually updated in Nextcloud interface when file is edited via OnlyOffice web app

omicron-b picture omicron-b on 29 Jun 2020

Hi @omicron-b ! What is the conclusion of this case? The error was only in not updating the last modified date?

bouskdav picture bouskdav on 19 Oct 2020

@bouskdav this is the actual issue: https://github.com/nextcloud/documentserver_community/issues/12
workaround in my case is switching background jobs to cron, see: https://docs.nextcloud.com/server/18/admin_manual/configuration_server/background_jobs_configuration.html

omicron-b picture omicron-b on 19 Oct 2020
👍1

@omicron-b awesome, thanks for quick reply sir!

bouskdav picture bouskdav on 19 Oct 2020
👍1
Was this page helpful?
0 / 5 - 0 ratings

Related issues

juliushaertl picture juliushaertl  路  3Comments
blackcrack picture blackcrack  路  3Comments
jancborchardt picture jancborchardt  路  3Comments
brylie picture brylie  路  3Comments
MariusBluem picture MariusBluem  路  3Comments