Failing to open documents after Update to Enterprise 7.2.1

[DSime]

Hey there!
After an update to OnlyOffice Enterprise 7.2.1 there is an issue with opening some documents.

After a rollback to the previous version everything's fine again.

Here is a part of the Log.

2022-11-22T12:25:07.000Z [2022-11-22T12:25:07.231] [WARN] [localhost] [docId] [userId] nodeJS - Express server starting...
2022-11-22T12:25:07.000Z [2022-11-22T12:25:07.260] [WARN] [localhost] [docId] [userId] nodeJS - Failed to subscribe to plugin folder updates. When changing the list of plugins, you must restart the server. https://nodejs.org/docs/latest/api/fs.html#fs_availability
2022-11-22T12:25:07.000Z [2022-11-22T12:25:07.264] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:07.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:07.000Z [2022-11-22T12:25:07.772] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:07.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:07.000Z 2022-11-22 12:25:07,776 INFO success: converter entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2022-11-22T12:25:07.000Z 2022-11-22 12:25:07,776 INFO success: converter entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2022-11-22T12:25:07.000Z 2022-11-22 12:25:07,777 INFO success: docservice entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2022-11-22T12:25:07.000Z 2022-11-22 12:25:07,777 INFO success: docservice entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2022-11-22T12:25:07.000Z 2022-11-22 12:25:07,778 INFO success: metrics entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2022-11-22T12:25:07.000Z 2022-11-22 12:25:07,778 INFO success: metrics entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2022-11-22T12:25:07.000Z 2022-11-22 12:25:07,779 INFO success: nginx entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2022-11-22T12:25:07.000Z 2022-11-22 12:25:07,779 INFO success: nginx entered RUNNING state, process has stayed up for > than 1 seconds (startsecs)
2022-11-22T12:25:07.000Z [2022-11-22T12:25:07.776] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:07.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:07.000Z [2022-11-22T12:25:07.784] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:07.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:07.000Z [2022-11-22T12:25:07.813] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:07.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:07.000Z [2022-11-22T12:25:07.831] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:07.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:07.000Z [2022-11-22T12:25:07.849] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:07.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:07.000Z [2022-11-22T12:25:07.914] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:07.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:08.000Z [2022-11-22T12:25:08.089] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:08.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:08.000Z [2022-11-22T12:25:08.267] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:08.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:08.000Z [2022-11-22T12:25:08.775] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:08.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:08.000Z [2022-11-22T12:25:08.780] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:08.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:08.000Z [2022-11-22T12:25:08.788] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:08.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:08.000Z [2022-11-22T12:25:08.816] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:08.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:08.000Z [2022-11-22T12:25:08.835] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:08.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:08.000Z [2022-11-22T12:25:08.852] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:08.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:08.000Z [2022-11-22T12:25:08.918] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:08.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:09.000Z [2022-11-22T12:25:09.092] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:09.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:09.000Z [2022-11-22T12:25:09.269] [ERROR] [localhost] [docId] [userId] nodeJS - [AMQP] Error: connect ECONNREFUSED 127.0.0.1:5672
2022-11-22T12:25:09.000Z at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16)
2022-11-22T12:25:09.000Z 
2022-11-22T12:25:09.000Z ##  ##      RabbitMQ 3.8.2
2022-11-22T12:25:09.000Z ##  ##
2022-11-22T12:25:09.000Z ##########  Copyright (c) 2007-2019 Pivotal Software, Inc.
2022-11-22T12:25:09.000Z ######  ##
2022-11-22T12:25:09.000Z ##########  Licensed under the MPL 1.1. Website: https://rabbitmq.com
2022-11-22T12:25:09.000Z 
2022-11-22T12:25:09.000Z Doc guides: https://rabbitmq.com/documentation.html
2022-11-22T12:25:09.000Z Support:    https://rabbitmq.com/contact.html
2022-11-22T12:25:09.000Z Tutorials:  https://rabbitmq.com/getstarted.html
2022-11-22T12:25:09.000Z Monitoring: https://rabbitmq.com/monitoring.html
2022-11-22T12:25:09.000Z 
2022-11-22T12:25:09.000Z Logs: /var/log/rabbitmq/rabbit@b86b6a4a-0ad7-443d-8716-6affecd8fdee.log
2022-11-22T12:25:09.000Z /var/log/rabbitmq/rabbit@b86b6a4a-0ad7-443d-8716-6affecd8fdee_upgrade.log
2022-11-22T12:25:09.000Z 
2022-11-22T12:25:09.000Z Config file(s): (none)
2022-11-22T12:25:09.000Z 
2022-11-22T12:25:09.000Z Starting broker... completed with 0 plugins.
=> Healtheck error got response status 5022022-11-22T12:25:10.000Z [2022-11-22T12:25:10.394] [WARN] [localhost] [docId] [userId] nodeJS - Express server listening on port 8000 in production-linux mode. Version: 7.2.1. Build: 34
2022-11-22T12:25:18.000Z [2022-11-22T12:25:18.568] [ERROR] [localhost] [1302030473] [ocfi59d1h0de_sbosse4] nodeJS - changesError: Error: Uncaught TypeError: Cannot read properties of null (reading 'asc_getSelectionType') Script: https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js Line: 8:2130357 userAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/107.0.0.0 Safari/537.36 platform: Win32 isLoadFullApi: true isDocumentLoadComplete: false StackTrace: TypeError: Cannot read properties of null (reading 'asc_getSelectionType')
2022-11-22T12:25:18.000Z at i.onSelectionChanged (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2130357)
2022-11-22T12:25:18.000Z at i.SetDisabled (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2139606)
2022-11-22T12:25:18.000Z at i.onEditingDisable (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2378275)
2022-11-22T12:25:18.000Z at D (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:7870)
2022-11-22T12:25:18.000Z at e.<anonymous> (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:8074)
2022-11-22T12:25:18.000Z at e.<anonymous> (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:1249)
2022-11-22T12:25:18.000Z at c (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:109801)
2022-11-22T12:25:18.000Z at e.trigger (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:109001)
2022-11-22T12:25:18.000Z at i.disableEditing (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2377421)
2022-11-22T12:25:18.000Z at i.onLongActionEnd (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2364427)
2022-11-22T12:25:19.000Z [2022-11-22T12:25:19.435] [ERROR] [localhost] [2462274415] [ocfi59d1h0de_sbosse3] nodeJS - changesError: Error: Uncaught TypeError: Cannot read properties of null (reading 'asc_getSelectionType') Script: https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js Line: 8:2130357 userAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/107.0.0.0 Safari/537.36 platform: Win32 isLoadFullApi: true isDocumentLoadComplete: false StackTrace: TypeError: Cannot read properties of null (reading 'asc_getSelectionType')
2022-11-22T12:25:19.000Z at i.onSelectionChanged (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2130357)
2022-11-22T12:25:19.000Z at i.SetDisabled (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2139606)
2022-11-22T12:25:19.000Z at i.onEditingDisable (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2378275)
2022-11-22T12:25:19.000Z at D (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:7870)
2022-11-22T12:25:19.000Z at e.<anonymous> (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:8074)
2022-11-22T12:25:19.000Z at e.<anonymous> (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:1249)
2022-11-22T12:25:19.000Z at c (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:109801)
2022-11-22T12:25:19.000Z at e.trigger (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:109001)
2022-11-22T12:25:19.000Z at i.disableEditing (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2377421)
2022-11-22T12:25:19.000Z at i.onLongActionEnd (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2364427)
2022-11-22T12:25:19.000Z [2022-11-22T12:25:19.533] [ERROR] [localhost] [1212199128] [ocfi59d1h0de_sbosse3] nodeJS - changesError: Error: Uncaught TypeError: Cannot read properties of null (reading 'asc_getSelectionType') Script: https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js Line: 8:2130357 userAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/107.0.0.0 Safari/537.36 platform: Win32 isLoadFullApi: true isDocumentLoadComplete: false StackTrace: TypeError: Cannot read properties of null (reading 'asc_getSelectionType')
2022-11-22T12:25:19.000Z at i.onSelectionChanged (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2130357)
2022-11-22T12:25:19.000Z at i.SetDisabled (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2139606)
2022-11-22T12:25:19.000Z at i.onEditingDisable (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2378275)
2022-11-22T12:25:19.000Z at D (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:7870)
2022-11-22T12:25:19.000Z at e.<anonymous> (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:8074)
2022-11-22T12:25:19.000Z at e.<anonymous> (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:1249)
2022-11-22T12:25:19.000Z at c (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:109801)
2022-11-22T12:25:19.000Z at e.trigger (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:109001)
2022-11-22T12:25:19.000Z at i.disableEditing (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2377421)
2022-11-22T12:25:19.000Z at i.onLongActionEnd (https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js:8:2364427)
2022-11-22T12:26:09.000Z 2022-11-22 12:26:09,590 INFO reaped unknown pid 549
2022-11-22T12:26:09.000Z 2022-11-22 12:26:09,590 INFO reaped unknown pid 549
2022-11-22T12:26:09.000Z 2022-11-22 12:26:09,591 INFO reaped unknown pid 550
2022-11-22T12:26:09.000Z 2022-11-22 12:26:09,591 INFO reaped unknown pid 550
2022-11-22T12:26:09.000Z 2022-11-22 12:26:09,591 INFO reaped unknown pid 584
2022-11-22T12:26:09.000Z 2022-11-22 12:26:09,591 INFO reaped unknown pid 584
2022-11-22T12:26:09.000Z 2022-11-22 12:26:09,591 INFO reaped unknown pid 554
2022-11-22T12:26:09.000Z 2022-11-22 12:26:09,591 INFO reaped unknown pid 554
2022-11-22T12:27:09.000Z 2022-11-22 12:27:09,661 INFO reaped unknown pid 603
2022-11-22T12:27:09.000Z 2022-11-22 12:27:09,661 INFO reaped unknown pid 603
2022-11-22T12:27:09.000Z 2022-11-22 12:27:09,661 INFO reaped unknown pid 604
2022-11-22T12:27:09.000Z 2022-11-22 12:27:09,661 INFO reaped unknown pid 604
2022-11-22T12:27:09.000Z 2022-11-22 12:27:09,661 INFO reaped unknown pid 638
2022-11-22T12:27:09.000Z 2022-11-22 12:27:09,661 INFO reaped unknown pid 638
2022-11-22T12:27:10.000Z 2022-11-22 12:27:10,663 INFO reaped unknown pid 608
2022-11-22T12:27:10.000Z 2022-11-22 12:27:10,663 INFO reaped unknown pid 608
2022-11-22T12:27:47.000Z 2022-11-22 12:27:47,707 WARN received SIGTERM indicating exit request
2022-11-22T12:27:47.000Z 2022-11-22 12:27:47,707 WARN received SIGTERM indicating exit request
2022-11-22T12:27:47.000Z 2022-11-22 12:27:47,707 INFO waiting for converter, docservice, metrics, nginx to die
2022-11-22T12:27:47.000Z 2022-11-22 12:27:47,707 INFO waiting for converter, docservice, metrics, nginx to die
2022-11-22T12:27:47.000Z 2022-11-22 12:27:47,717 INFO stopped: nginx (exit status 0)
2022-11-22T12:27:47.000Z 2022-11-22 12:27:47,717 INFO stopped: nginx (exit status 0)
2022-11-22T12:27:47.000Z Flushing stats at  Tue Nov 22 2022 12:27:48 GMT+0000 (Coordinated Universal Time)

[nebulon]

@DSime since you mention "some documents", is there any pattern/document type which does not work anymore?

[DSime]

@nebulon It seems random to me. Sometimes it's an .xlsx I just created, sometimes a .docx created and opened months ago. Some of the files were created by me and some of them by others. Can't see any specific pattern. Some are working fine and others don't. It's a little weird. I thought clearing the browser-cache could help like in previous Onlyoffice-updates but nothing changed noticeably.

[subven]

2022-11-22T12:25:18.000Z [2022-11-22T12:25:18.568] [ERROR] [localhost] [1302030473] [ocfi59d1h0de_sbosse4] nodeJS - changesError: Error: Uncaught TypeError: Cannot read properties of null (reading 'asc_getSelectionType') Script: https://onlyenterprise.XXXXXXXXX.cloud/7.2.1-34/web-apps/apps/spreadsheeteditor/main/app.js Line: 8:2130357 userAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/107.0.0.0 Safari/537.36 platform: Win32 isLoadFullApi: true isDocumentLoadComplete: false StackTrace: TypeError: Cannot read properties of null (reading 'asc_getSelectionType')

This seems to be the part where it fails to open the document.

[subven]

@DSime said in Failing to open documents after Update to Enterprise 7.2.1:

After a rollback to the previous version everything's fine again.

Maybe the issue is upstream? Have you checked with a clean browser without any plugins enabled? Hard to pin down if it's not reproducable but at least we have log output.