ERA 7.2 and agent sync error (LoadUser: User state deserialization failed)

[scree 0]

ERA 7.2 Server: last-error.html

CReplicationModule	2020-Nov-02 15:22:40	CStepProcessor: Replication master validation process failed with: Object d97d89e6-2b96-4ace-b493-5a4a9d2fa7f5 was not found (LoadUser: User state deserialization failed)
NetworkModule	2020-Nov-02 15:22:40	Connection closed by remote peer for session id 1163

Client Agent 7.2: status.html

Last replication

2020-Nov-05 09:58:30

ERROR: SendRequestAndHandleResponse: Rpc message response INTERNAL_ERROR. Error message: Replication details: [Task: CStaticObjectMetadataTask, Scenario: Automatic replication (REGULAR), Connection: 192.168.6.31:2222, Connection established: true, Replication inconsistency detected: false, Server busy state detected: false, Realm change detected: false, Realm uuid: 00000000-0000-0000-0000-000000000000, Sent logs: 0, Cached static objects: 1, Cached static object groups: 1, Static objects to save: 0, Static objects to delete: 0, Modified static objects: 0] All replication attempts: 477

This was happened after update Linux installation from 6.5 of ERA to 7.2

I created new certification authority and Peer (server, agent) certificates.

Server status log is OK, no errors. And in server trace.log I cannnot see any critical errors also. This problem seems to be with agents only.

Any advance tips I really appriciate.

 

 

[Marcos 5,074]

Please upload status.html as well as trace.log.

[MartinK 378]

Please could you also provide more details of your environment, and especially how update was performed? From first logs it seems that here is some problem with ESMC's database consistency, possibly resulting in rejecting of all AGENT connections. Unfortunately errors are few days older so it is not sure, but issue seems to be not related to certificates.

Any chance database was reverted or restored from backup? Or any issues were encountered during update?

[scree 0]

Linux distribution is Debian 9.  I did a manual update by this tutorial: https://support.eset.com/en/kb6734-upgrade-eset-remote-administrator-63-and-later-for-linux-to-the-latest-eset-security-management-center-7

I am using the old database from previous ERA manager 6.5 so maybe that is the problem. Probably I can try to reinstall the ERA Security Center with new database ?

Here is trace.log from server

2020-11-05 17:25:02 Debug: SchedulerModule [Thread 7fbb181cd700]: CTimeScheduler : Invoking SleepEventHandler id: 12820
2020-11-05 17:25:02 Debug: SchedulerModule [Thread 7fbb181cd700]: CTimeScheduler : Finished invoking SleepEventHandler id: 12820
2020-11-05 17:25:02 Debug: CDataMinersModule [Thread 7fbb157de700]: Processing ETL Log data
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb157de700]: ETL CLogsETLMapper: Starting plan for logtype: QOS_DATABASE_EVENT - number of logs:1
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb157de700]: ETL CLogsETLMapper: Finishing plan for logtype: QOS_DATABASE_EVENT. LogCount: 1. Duration: 7ms (total) 2ms (cpu). Statistics: {Total: 0ms. "scan1": (0ms, 1), "insertFact": (0ms, 1), }. Parsing: 0ms, init: 0ms, exec: 1ms. Enable-dim-opt: 0.
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb157de700]: ETL CLogsETLMapper: Starting plan for logtype: PERFORMANCE_SERVER_EVENT - number of logs:1
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb157de700]: ETL CLogsETLMapper: Finishing plan for logtype: PERFORMANCE_SERVER_EVENT. LogCount: 1. Duration: 7ms (total) 1ms (cpu). Statistics: {Total: 0ms. "scan1": (0ms, 1), "insertFact": (0ms, 1), }. Parsing: 0ms, init: 0ms, exec: 1ms. Enable-dim-opt: 0.
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbaeff93700]: ETL Clients: Updating of log types [38, 131, ]...
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbaeff93700]: ETL Clients: Updating of log types [38, 131, ] takes: 0ms (total) 0ms (cpu)
2020-11-05 17:25:02 Information: AutomationModule [Thread 7fbaf87a4700]: StaticObjectChangeHandler: ScheduleCheck: Next static object changes check in 15 seconds.
2020-11-05 17:25:02 Information: SchedulerModule [Thread 7fbb14fdd700]: Received message: RegisterSleepEvent
2020-11-05 17:25:02 Debug: SchedulerModule [Thread 7fbb14fdd700]: CTimeScheduler : RegisterSleepEventWithTimer: next occurence of id: 12821 at: 2020-Nov-05 17:25:17
2020-11-05 17:25:02 Debug: SchedulerModule [Thread 7fbb14fdd700]: CTimeScheduler : New sleep event was registered.
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Resetting mapper state
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Rebuilding static objects cache
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Acquiring database connection
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Successfully acquired database connection
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Fetching 1318 changed objects data to static objects cache
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Fetching all COMPUTER objects
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Finnished batch loading of COMPUTER objects
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Fetching all CLIENT_TASK_TO_COMPUTER_RELATION, CLIENT_TASK_TO_DYNAMIC_GROUP_RELATION and CLIENT_TASK_TO_STATIC_GROUP_RELATION objects
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Finnished batch loading of CLIENT_TASK_TO_COMPUTER_RELATION, CLIENT_TASK_TO_DYNAMIC_GROUP_RELATION and CLIENT_TASK_TO_STATIC_GROUP_RELATION objects
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Fetching all STAFF_USER objects
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Finnished batch loading of STAFF_USER objects
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Fetching all STAFF_USER_COMPUTER_RELATION objects
2020-11-05 17:25:02 Information: CDatabaseModule [Thread 7fbb027b8700]: ReplicationStaticObjectsMapper: Finnished batch loading of STAFF_USER_COMPUTER_RELATION objects
2020-11-05 17:25:02 Error: CReplicationModule [Thread 7fbb027b8700]: RpcGetStaticObjectsHandler: Failed to load static objects list for peer f12824f7-e4fa-459d-9d53-2ec2cb9fcb67 with error: Object d97d89e6-2b96-4ace-b493-5a4a9d2fa7f5 was not found (LoadUser: User state deserialization failed)
2020-11-05 17:25:02 Debug: CReplicationModule [Thread 7fbb027b8700]: RpcGetStaticObjectsHandler: Processed request with response 'status { status: INTERNAL_ERROR }'
2020-11-05 17:25:05 Information: SchedulerModule [Thread 7fbb14fdd700]: Received message: RegisterSleepEvent
2020-11-05 17:25:05 Debug: SchedulerModule [Thread 7fbb14fdd700]: CTimeScheduler : RegisterSleepEventWithTimer: next occurence of id: 12822 at: 2020-Nov-05 17:25:07
2020-11-05 17:25:05 Debug: SchedulerModule [Thread 7fbb14fdd700]: CTimeScheduler : New sleep event was registered.
2020-11-05 17:25:07 Debug: SchedulerModule [Thread 7fbb181cd700]: CTimeScheduler : Invoking SleepEventHandler id: 12822
2020-11-05 17:25:07 Debug: SchedulerModule [Thread 7fbb181cd700]: CTimeScheduler : Finished invoking SleepEventHandler id: 12822
2020-11-05 17:25:07 Debug: CDataMinersModule [Thread 7fbb157de700]: Processing ETL Log data
2020-11-05 17:25:07 Information: CDatabaseModule [Thread 7fbb157de700]: ETL CLogsETLMapper: Starting plan for logtype: QOS_NETWORK_EVENT - number of logs:1
2020-11-05 17:25:07 Information: CDatabaseModule [Thread 7fbb157de700]: ETL CLogsETLMapper: Finishing plan for logtype: QOS_NETWORK_EVENT. LogCount: 1. Duration: 7ms (total) 1ms (cpu). Statistics: {Total: 0ms. "scan1": (0ms, 1), "insertFact": (0ms, 1), }. Parsing: 0ms, init: 0ms, exec: 0ms. Enable-dim-opt: 0.
2020-11-05 17:25:07 Information: CDatabaseModule [Thread 7fbaeff93700]: ETL Clients: Updating of log types [39, ]...
2020-11-05 17:25:07 Information: CDatabaseModule [Thread 7fbaeff93700]: ETL Clients: Updating of log types [39, ] takes: 0ms (total) 0ms (cpu)
2020-11-05 17:25:17 Debug: SchedulerModule [Thread 7fbb181cd700]: CTimeScheduler : Invoking SleepEventHandler id: 12821
2020-11-05 17:25:17 Debug: SchedulerModule [Thread 7fbb181cd700]: CTimeScheduler : Finished invoking SleepEventHandler id: 12821
2020-11-05 17:25:17 Information: AutomationModule [Thread 7fbaf87a4700]: StaticObjectChangeHandler: PerformCheckForWork: Starting to check for new work.

thank you for help

[scree 0]

Hello, ok so i started with empty database, reinstall Eset and Agent seems to be ok to connect. Thank you for pointing me, that it was some issues with database.

Jan