После обновления до Acronis Cyber Backup 12.5.16180 перестали приходить уведомления по электронной почте
Здравствуйте.
После обновления с версии Acronis Cyber Backup 12.5.14330 до 12.5.16180 перестали приходить уведомления по электронной почте. При этом Ежедневные краткие сведения об активных оповещениях приходят исправно. Во всех планах в параметрах резервного копирования в настройках уведомлений по электронной почте выбрано использовать глобальные настройки. Я правильно понимаю, что в случае сбоя плана резервного копирования уведомление по электронной почте, если оно настроено, осуществляет сервер управления, а не агент с машины, на которой произошёл сбой?
Если на сервере управления посмотреть содержимое лог-файла по пути c:\ProgramData\Acronis\NotificationService\Logs\notification_service.log, то видно, что непосредственно перед, в процессе или после обновления появились две повторяющиеся ошибки (0x30002 и 0xf90004), которые прекратились в течение суток:
2020-05-23 00:58:00,612 - WARNING - Error 0x30002
| line: 0x37819fd1e1f7a71c
| file: e:\528\core\network\pipe_win.cpp:1076
| function: Network::ConnectToNamedPipe
| $module: asyncipc_vsa64_14330
|
| error 0xfff0: Не удается найти указанный файл
| line: 0xbd28fdbd64edb8f8
| file: e:\528\core\common\error.cpp:314
| function: Common::Error::AddWindowsError
| code: 0x80070002
| $module: asyncipc_vsa64_14330
Failed to do 'subscribe((<notification_service.logic.managers.ams_connection.AmsConnection object at 0x0000006C28DCAAC8>, [<acrort.ViewSpec object at 0x0000006C28E2A1B8>, <acrort.ViewSpec object at 0x0000006C28E2A340>, <acrort.ViewSpec object at 0x0000006C28E2A538>]), {})' operation. It was 1 try.
Next retry after 0:00:01
Traceback (most recent call last):
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\__init__.py", line 31, in wrapped_operation
return await func(*args, **kwargs)
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\ams_connection.py", line 190, in subscribe
await self._ensure_connection()
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\ams_connection.py", line 225, in _ensure_connection
self._connection = await self._connect()
File "<string>", line 3, in __await__
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1008, in run
value = future.result()
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\concurrent.py", line 232, in result
raise_exc_info(self._exc_info)
File "<string>", line 3, in raise_exc_info
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1014, in run
yielded = self.gen.throw(*exc_info)
File "c:\program files\acronis\pyshell\site-packages\scarf.zip\scarf\thread_pool.py", line 43, in wrap
result = yield cls.THREAD_POOL.submit(func, *args, **kwargs)
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1008, in run
value = future.result()
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\_base.py", line 398, in result
return self.__get_result()
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\_base.py", line 357, in __get_result
raise self._exception
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\thread.py", line 55, in run
result = self.fn(*self.args, **self.kwargs)
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\ams_connection.py", line 229, in _connect
return acrort.connectivity.Connection('ams', queue=self._queue, client_session_data={"identity_disabled": True})
acrort.Exception: Error 0x30002
| line: 0x37819fd1e1f7a71c
| file: e:\528\core\network\pipe_win.cpp:1076
| function: Network::ConnectToNamedPipe
| $module: asyncipc_vsa64_14330
|
| error 0xfff0: Не удается найти указанный файл
| line: 0xbd28fdbd64edb8f8
| file: e:\528\core\common\error.cpp:314
| function: Common::Error::AddWindowsError
| code: 0x80070002
| $module: asyncipc_vsa64_143302020-05-23 01:02:40,655 - WARNING - Error 0xf90004: Dml::AsyncServer::State::JobPool
| line: 0x20f14f64de1eccab
| file: e:\497\enterprise\common\dml\messaging\async_server.cpp:241
| function: Dml::Messaging::AsyncServer::State::State
| $module: management_server_vsa64_14330
|
| error 0x1350001: Operation timed out after 30000 milliseconds with 0 bytes received
| line: 0x91fd1783976e2d38
| file: e:\497\enterprise\common\tol\tm_supp\client.cpp:373
| function: Tol::TmClient::Http::DoRequest
| $module: management_server_vsa64_14330
Failed to do 'select_object((<notification_service.logic.managers.ams_connection.AmsConnection object at 0x000000A553FCAE80>, <acrort.ViewSpec object at 0x000000A553FCC688>), {})' operation. It was 1 try.
Next retry after 0:00:01
Traceback (most recent call last):
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\__init__.py", line 31, in wrapped_operation
return await func(*args, **kwargs)
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\ams_connection.py", line 164, in select_object
return await self._select_object(spec)
File "<string>", line 3, in __await__
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1008, in run
value = future.result()
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\concurrent.py", line 232, in result
raise_exc_info(self._exc_info)
File "<string>", line 3, in raise_exc_info
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1014, in run
yielded = self.gen.throw(*exc_info)
File "c:\program files\acronis\pyshell\site-packages\scarf.zip\scarf\thread_pool.py", line 43, in wrap
result = yield cls.THREAD_POOL.submit(func, *args, **kwargs)
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1008, in run
value = future.result()
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\_base.py", line 398, in result
return self.__get_result()
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\_base.py", line 357, in __get_result
raise self._exception
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\thread.py", line 55, in run
result = self.fn(*self.args, **self.kwargs)
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\ams_connection.py", line 233, in _select_object
return acrobind.select_object(self._connection, spec)
File "c:\program files\acronis\pyshell\site-packages\acrobind.zip\acrobind\dml_utils.py", line 322, in select_object
objects = select_objects(connection, spec)
File "c:\program files\acronis\pyshell\site-packages\acrobind.zip\acrobind\dml_utils.py", line 316, in select_objects
objects = connection.dml.select(spec)
acrort.Exception: Error 0xf90004: Dml::AsyncServer::State::JobPool
| line: 0x20f14f64de1eccab
| file: e:\497\enterprise\common\dml\messaging\async_server.cpp:241
| function: Dml::Messaging::AsyncServer::State::State
| $module: management_server_vsa64_14330
|
| error 0x1350001: Operation timed out after 30000 milliseconds with 0 bytes received
| line: 0x91fd1783976e2d38
| file: e:\497\enterprise\common\tol\tm_supp\client.cpp:373
| function: Tol::TmClient::Http::DoRequest
| $module: management_server_vsa64_143302020-05-23 01:59:48,028 - WARNING - Error 0x30002
| line: 0x37819fd1e1f7a71c
| file: e:\715\core\network\pipe_win.cpp:1076
| function: Network::ConnectToNamedPipe
| $module: asyncipc_vsa64_16180
|
| error 0xfff0: Не удается найти указанный файл
| line: 0xbd28fdbd64edb8f8
| file: e:\715\core\common\error.cpp:314
| function: Common::Error::AddWindowsError
| code: 0x80070002
| $module: asyncipc_vsa64_16180
Failed to do 'select_object((<notification_service.logic.managers.ams_connection.AmsConnection object at 0x0000004DC5BC9C50>, <acrort.ViewSpec object at 0x0000004DC5BC9CA8>), {})' operation. It was 4 try.
Next retry after 0:00:08
Traceback (most recent call last):
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\__init__.py", line 31, in wrapped_operation
return await func(*args, **kwargs)
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\ams_connection.py", line 163, in select_object
await self._ensure_connection()
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\ams_connection.py", line 225, in _ensure_connection
self._connection = await self._connect()
File "<string>", line 3, in __await__
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1008, in run
value = future.result()
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\concurrent.py", line 232, in result
raise_exc_info(self._exc_info)
File "<string>", line 3, in raise_exc_info
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1014, in run
yielded = self.gen.throw(*exc_info)
File "c:\program files\acronis\pyshell\site-packages\scarf.zip\scarf\thread_pool.py", line 43, in wrap
result = yield cls.THREAD_POOL.submit(func, *args, **kwargs)
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1008, in run
value = future.result()
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\_base.py", line 398, in result
return self.__get_result()
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\_base.py", line 357, in __get_result
raise self._exception
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\thread.py", line 55, in run
result = self.fn(*self.args, **self.kwargs)
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\ams_connection.py", line 229, in _connect
return acrort.connectivity.Connection('ams', queue=self._queue, client_session_data={"identity_disabled": True})
acrort.Exception: Error 0x30002
| line: 0x37819fd1e1f7a71c
| file: e:\715\core\network\pipe_win.cpp:1076
| function: Network::ConnectToNamedPipe
| $module: asyncipc_vsa64_16180
|
| error 0xfff0: Не удается найти указанный файл
| line: 0xbd28fdbd64edb8f8
| file: e:\715\core\common\error.cpp:314
| function: Common::Error::AddWindowsError
| code: 0x80070002
| $module: asyncipc_vsa64_161802020-05-23 10:57:18,884 - WARNING - Error 0xf90004: Dml::AsyncServer::State::JobPool
| line: 0x20f14f64de1eccab
| file: e:\680\enterprise\common\dml\messaging\async_server.cpp:241
| function: Dml::Messaging::AsyncServer::State::State
| $module: management_server_vsa64_16180
|
| error 0x1350001: Operation timed out after 30000 milliseconds with 0 bytes received
| line: 0x91fd1783976e2d38
| file: e:\680\enterprise\common\tol\tm_supp\client.cpp:373
| function: Tol::TmClient::Http::DoRequest
| $module: management_server_vsa64_16180
Failed to do 'select_object((<notification_service.logic.managers.ams_connection.AmsConnection object at 0x000000F932285940>, <acrort.ViewSpec object at 0x000000F932285F80>), {})' operation. It was 1 try.
Next retry after 0:00:01
Traceback (most recent call last):
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\__init__.py", line 31, in wrapped_operation
return await func(*args, **kwargs)
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\ams_connection.py", line 164, in select_object
return await self._select_object(spec)
File "<string>", line 3, in __await__
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1008, in run
value = future.result()
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\concurrent.py", line 232, in result
raise_exc_info(self._exc_info)
File "<string>", line 3, in raise_exc_info
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1014, in run
yielded = self.gen.throw(*exc_info)
File "c:\program files\acronis\pyshell\site-packages\scarf.zip\scarf\thread_pool.py", line 43, in wrap
result = yield cls.THREAD_POOL.submit(func, *args, **kwargs)
File "c:\program files\acronis\pyshell\site-packages\tornado.zip\tornado\gen.py", line 1008, in run
value = future.result()
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\_base.py", line 398, in result
return self.__get_result()
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\_base.py", line 357, in __get_result
raise self._exception
File "C:\Program Files\Acronis\PyShell\stdlib.zip\concurrent\futures\thread.py", line 55, in run
result = self.fn(*self.args, **self.kwargs)
File "C:\Program Files\Acronis\NotificationService\notification-service.zip\notification_service\logic\managers\ams_connection.py", line 233, in _select_object
return acrobind.select_object(self._connection, spec)
File "c:\program files\acronis\pyshell\site-packages\acrobind.zip\acrobind\dml_utils.py", line 322, in select_object
objects = select_objects(connection, spec)
File "c:\program files\acronis\pyshell\site-packages\acrobind.zip\acrobind\dml_utils.py", line 316, in select_objects
objects = connection.dml.select(spec)
acrort.Exception: Error 0xf90004: Dml::AsyncServer::State::JobPool
| line: 0x20f14f64de1eccab
| file: e:\680\enterprise\common\dml\messaging\async_server.cpp:241
| function: Dml::Messaging::AsyncServer::State::State
| $module: management_server_vsa64_16180
|
| error 0x1350001: Operation timed out after 30000 milliseconds with 0 bytes received
| line: 0x91fd1783976e2d38
| file: e:\680\enterprise\common\tol\tm_supp\client.cpp:373
| function: Tol::TmClient::Http::DoRequest
| $module: management_server_vsa64_16180

- Anmelden, um Kommentare verfassen zu können

Hello Yury,
our QA team didn't reproduce the issue (tested update from 14330). If after running the repair installation you'll still observe the same issue, could you please share the following details with us:
- what SMTP provider is used
- what SQL is used for Acronis Management Server
- Acronis system report from AMS (sent you the upload link in a private message)
Thank you!
- Anmelden, um Kommentare verfassen zu können

Здравствуйте, Мария и Екатерина.
В качестве SMTP сервера используется Kerio Connect, но с этим проблем нет (только иногда бывает таймаут рукопожатия по SSL, но это уже другая проблема), при этом тестовое сообщение успешно отправляется, также как и ежедневный отчёт.
В качестве баз(ы) данных используется SQLite.
Переустановка (восстановление) Acronis Management Server не помогла.
Как я понял при работе служба уведомлений делает 15 попыток по 30 секунд для подключения через именованные каналы к службе AMS, и 30 секунд недостаточно, чтобы выполнить некий запрос?
- Anmelden, um Kommentare verfassen zu können

Удаление какой-то из этих баз помогло:
c:\ProgramData\Acronis\AMS\dml_archives.db3 (4 Гб)
c:\ProgramData\Acronis\TaskManager\task_manager_db.sqlite3 (3,6 Гб)
c:\ProgramData\Acronis\VaultManager\acronis_vault_manager.db3 (840 Мб)
- Anmelden, um Kommentare verfassen zu können

Hello Yury.
Thank you for sharing the outcome.
Since you have already deleted the AMS database, we can't say what was the root cause of this issue (unless you have uploaded Acronis System Information before deletion upon Ekaterina's request).
Please note that we do not recommend deleting the AMS database but instead investigating the reasons of its growth. Please let us know if installing a new build has stopped this process.
- Anmelden, um Kommentare verfassen zu können