The Veritas Remote Agent crashes unfrequently during startup of the backup job.
This happens in the last 4 week about 5 times.
The following error is reported in the bengine00.log after enabeling the debig log mode:
1e38 7/18/2006 1:00:34: Caught message on closed connection. Socket 0x4f4 len 0xffffffff
1e38 7/18/2006 1:00:34: ndmp_process_messages: detected eof
1e38 7/18/2006 1:00:34: ERROR: ndmpcSendRequest->connection error
1e38 7/18/2006 1:00:34:
dataStartBackup: ndmpSendRequest returned: 0xffffffff, -1
1e38 7/18/2006 1:00:34: ERROR: ndmpSendRequest failed:
1e38 7/18/2006 1:00:34: ndmpFreeMessage: message:0x0
14f0 7/18/2006 1:00:34: Caught message on closed connection. Socket 0x4c8 len 0xffffffff
1e38 7/18/2006 1:00:34:
dataStartBackup: ERROR returning: 0x17, 23
14f0 7/18/2006 1:00:34: ndmp_process_messages: detected eof
1e38 7/18/2006 1:00:34: ndmpSendRequest: message:0x407
14f0 7/18/2006 1:00:34: ERROR: ndmpcSendRequest->connection error
1e38 7/18/2006 1:00:34: sent seq#: 8 msg: 0x407
14f0 7/18/2006 1:00:34:
dataStartBackup: ndmpSendRequest returned: 0xffffffff, -1
1e38 7/18/2006 1:00:34: ndmp_recv_msg
14f0 7/18/2006 1:00:34: ERROR: ndmpSendRequest failed:
1e38 7/18/2006 1:00:34: Caught message on closed connection. Socket 0x4f4 len 0xffffffff
14f0 7/18/2006 1:00:34: ndmpFreeMessage: message:0x0
1e38 7/18/2006 1:00:34: Caught message on closed connection. Socket 0x4f4 len 0xffffffff
14f0 7/18/2006 1:00:34:
dataStartBackup: ERROR returning: 0x17, 23
1e38 7/18/2006 1:00:34: ndmp_process_messages: detected eof
14f0 7/18/2006 1:00:34: ndmpSendRequest: message:0x407
1e38 7/18/2006 1:00:34: ERROR: ndmpcSendRequest->connection error
14f0 7/18/2006 1:00:34: sent seq#: 8 msg: 0x407
1e38 7/18/2006 1:00:34: ERROR: ndmpSendRequest failed:
14f0 7/18/2006 1:00:34: ndmp_recv_msg
1e38 7/18/2006 1:00:34: ndmpFreeMessage: message:0x0
14f0 7/18/2006 1:00:34: Caught message on closed connection. Socket 0x4c8 len 0xffffffff
14f0 7/18/2006 1:00:34: Caught message on closed connection. Socket 0x4c8 len 0xffffffff
14f0 7/18/2006 1:00:34: ndmp_process_messages: detected eof
14f0 7/18/2006 1:00:34: ERROR: ndmpcSendRequest->connection error
14f0 7/18/2006 1:00:34: ERROR: ndmpSendRequest failed:
14f0 7/18/2006 1:00:34: ndmpFreeMessage: message:0x0
1e38 7/18/2006 1:00:34: ndmpSendRequest: message:0x902
1e38 7/18/2006 1:00:34: sent seq#: 9 msg: 0x902
14f0 7/18/2006 1:00:34: ndmpSendRequest: message:0x902
1e38 7/18/2006 1:00:34: ndmpFreeMessage: message:0x0
1e38 7/18/2006 1:00:34: ndmpClose
14f0 7/18/2006 1:00:34: sent seq#: 9 msg: 0x902
1e38 7/18/2006 1:00:34: ndmpDestroyConnection
14f0 7/18/2006 1:00:34: ndmpFreeMessage: message:0x0
2064 7/18/2006 1:00:34: DeviceManager: incoming event fired
14f0 7/18/2006 1:00:34: ndmpClose
2064 7/18/2006 1:00:34: DeviceManager: processing pending requests
14f0 7/18/2006 1:00:34: ndmpDestroyConnection
2064 7/18/2006 1:00:34: DeviceManager: query cache miss
2064 7/18/2006 1:00:34: DeviceManager: query result = 0x00000000
2064 7/18/2006 1:00:34: Available and held drives from the pool
2064 7/18/2006 1:00:34: PACS_IX-SQL-Log-Backup2 {63338625-17ff-4eb0-8a2c-63415a87744f}
2064 7/18/2006 1:00:34: Drives not in use by the Engine
2064 7/18/2006 1:00:34: PACS_IX-SQL-Log-Backup2 {63338625-17ff-4eb0-8a2c-63415a87744f}
2064 7/18/2006 1:00:34: Drives available (not in use and not held)
2064 7/18/2006 1:00:34: PACS_IX-SQL-Log-Backup2 {63338625-17ff-4eb0-8a2c-63415a87744f}
2064 7/18/2006 1:00:34: Handing out drive:
2064 7/18/2006 1:00:34: PACS_IX-SQL-Log-Backup2 {63338625-17ff-4eb0-8a2c-63415a87744f}
2064 7/18/2006 1:00:34: DeviceManager: going to sleep for 900000 msecs
2064 7/18/2006 1:00:34: DeviceManager: incoming event fired
2064 7/18/2006 1:00:34: DeviceManager: stalling
2064 7/18/2006 1:00:34: DeviceManager: going to sleep for 30000 msecs
1e38 7/18/2006 1:00:34: BackupJob: acquired device PACS_IX-SQL-Log-Backup2
1e38 7/18/2006 1:00:34: BackupJob: trying to mount scratch volume
Does anyone know, what the root cause could be ?
Thanks, Bernd Schlaupitz
