Full backup fails with error 156

[rookie11]

Hi all

client OS windows 2000, NBU version 6.5.3, master n media 7.1

Issue is when incremental backup runs it goes fine but problem is with every full backup schedule it fils with error 156

below is bpbkar log files of differeent backup schedule dates.

1:48:40.593 AM: [5904.5520] <4> ov_log::OVLoop: Timestamp

1:48:40.593 AM: [5904.5520] <4> OVStopCmd: INF - EXIT - status = 0

1:48:40.593 AM: [5904.5520] <2> tar_base::V_Close: 

1:48:40.593 AM: [5904.5520] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

1:48:40.593 AM: [5904.5520] <32> TransporterRemote::write[2](): FTL - SocketWriteException: send() call failed, could not write data to the socket, possible broken connection.

1:48:41.015 AM: [5904.5520] <32> Packer::close(): FTL - OperationFailedException: close operation failed.

1:48:41.031 AM: [5904.5520] <2> ov_log::V_GlobalLog: INF - BEDS_Term() Enter InitFlags:0x1

1:48:41.250 AM: [5904.5520] <16> dtcp_read: TCP - failure: recv socket (472) (TCP 10053: Software caused connection abort)

1:48:41.250 AM: [5904.5520] <4> OVShutdown: INF - Finished process

1:48:41.281 AM: [5904.5520] <4> WinMain: INF - Exiting C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe

1:48:43.281 AM: [5904.5520] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\121011.LOG

2:03:37.875 AM: [2292.4824] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\121011.LOG

2:03:37.875 AM: [2292.4824] <4> ov_log::OVInit: GENERAL Log Level: 0

2:03:37.875 AM: [2292.4824] <4> ov_log::OVInit: TCP Log Level: 0

2:03:37.875 AM: [2292.4824] <4> ov_log::OVInit: INF - the log mutex: 772

BPBKAR  NetBackup Backup/Archive  6.5GA  [Oct 30 2008]

2:52:48.437 AM: [3832.4980] <4> OVStopCmd: INF - EXIT - status = 0

2:52:48.437 AM: [3832.4980] <2> tar_base::V_Close: 

2:52:48.437 AM: [3832.4980] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

2:52:48.437 AM: [3832.4980] <32> TransporterRemote::write[2](): FTL - SocketWriteException: send() call failed, could not write data to the socket, possible broken connection.

2:52:48.437 AM: [3832.4980] <32> Packer::close(): FTL - OperationFailedException: close operation failed.

2:52:48.437 AM: [3832.4980] <2> ov_log::V_GlobalLog: INF - BEDS_Term() Enter InitFlags:0x1

2:52:48.453 AM: [3832.4980] <16> dtcp_read: TCP - failure: recv socket (492) (TCP 10053: Software caused connection abort)

2:52:48.453 AM: [3832.4980] <4> OVShutdown: INF - Finished process

2:52:48.484 AM: [3832.4980] <4> WinMain: INF - Exiting C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe

2:52:50.484 AM: [3832.4980] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\121711.LOG

1:32:56.265 AM: [4084.4056] <16> dtcp_write: TCP - failure: send socket (708) (TCP 10053: Software caused connection abort)

1:32:56.265 AM: [4084.4056] <16> dtcp_write: TCP - failure: attempted to send 86 bytes

1:32:56.265 AM: [4084.4056] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

1:32:56.265 AM: [4084.4056] <4> OVStopCmd: INF - EXIT - status = 0

1:32:56.265 AM: [4084.4056] <2> tar_base::V_Close: 

1:32:56.265 AM: [4084.4056] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

1:32:56.281 AM: [4084.4056] <2> ov_log::V_GlobalLog: INF - BEDS_Term() Enter InitFlags:0x1

1:32:56.406 AM: [4084.4056] <16> dtcp_read: TCP - failure: recv socket (708) (TCP 10053: Software caused connection abort)

1:32:56.406 AM: [4084.4056] <4> OVShutdown: INF - Finished process

1:32:56.437 AM: [4084.4056] <4> WinMain: INF - Exiting C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe

1:32:58.437 AM: [4084.4056] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\122311.LOG

2:48:52.281 AM: [768.1736] <2> tar_base::backup_finish: TAR - backup:                     205678 files

2:48:52.281 AM: [768.1736] <2> tar_base::backup_finish: TAR - backup:          file data:  652649010 bytes  92 gigabytes

2:48:52.281 AM: [768.1736] <2> tar_base::backup_finish: TAR - backup:         image data:  119217152 bytes  93 gigabytes

2:48:52.281 AM: [768.1736] <2> tar_base::backup_finish: TAR - backup:       elapsed time:       7464 secs     13394588 bps

2:48:52.281 AM: [768.1736] <4> ov_log::OVLoop: Timestamp

2:48:52.281 AM: [768.1736] <4> tar_backup::backup_done_state: INF - number of file directives not found: 0

2:48:52.281 AM: [768.1736] <4> tar_backup::backup_done_state: INF -     number of file directives found: 3

2:48:52.281 AM: [768.2476] <4> tar_base::keepaliveThread: INF - keepalive thread terminating (reason: WAIT_OBJECT_0)

2:48:52.281 AM: [768.1736] <4> tar_base::stopKeepaliveThread: INF - keepalive thread has exited. (reason: WAIT_OBJECT_0)

2:48:52.281 AM: [768.1736] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 0: the requested operation was successfully completed

2:48:56.234 AM: [768.1736] <16> dtcp_write: TCP - failure: send socket (704) (TCP 10053: Software caused connection abort)

2:48:56.234 AM: [768.1736] <16> dtcp_write: TCP - failure: attempted to send 86 bytes

2:48:56.234 AM: [768.1736] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

2:48:56.234 AM: [768.1736] <4> OVStopCmd: INF - EXIT - status = 0

2:48:56.234 AM: [768.1736] <2> tar_base::V_Close: 

2:48:56.234 AM: [768.1736] <4> dos_backup::tfs_reset: INF - Snapshot deletion start

2:48:56.312 AM: [768.1736] <2> ov_log::V_GlobalLog: INF - BEDS_Term() Enter InitFlags:0x1

2:48:56.468 AM: [768.1736] <16> dtcp_read: TCP - failure: recv socket (704) (TCP 10053: Software caused connection abort)

2:48:56.468 AM: [768.1736] <4> OVShutdown: INF - Finished process

2:48:56.484 AM: [768.1736] <4> WinMain: INF - Exiting C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe

2:48:58.484 AM: [768.1736] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\123111.LOG

[Mark_Solutions]

The 10053 error is Network related rather than snapshot related

It may be that during the Full backup the snapshot it taking too long or getting too big.

Two things to do initially:

1. Increase the client read timeouts (client and media sever) to a higher value (1800 or 3600)

2. Tune the VSP file for the client - Go to its client host properties - VSP tab. Set the maximum cache size to less than the free disk space on the server (it defaults to 30GB) and also select a specific drive or drives for it to use for the cache files (just put in an Upper Case letter or letters, seperated by a comma) - do not put anything in the Exclude drive section. Also clcik to customise the cache, select MB and set it at 125MB with say a maximum of 3000MB

If it still fails then check all network settings.

Also check the client for ophaned bpfis processes and that the drives have not been filled with *.VSP files

If it does have these you can use Process Explorer to break the handles on these or just reboot it and then delete them

Let us know how you get on

(and then upgrade it to 6.5.6 as there are lots of bug fixes)

[rookie11]

past 4 full backups failed , past 4 weeks.  on previous friday i did the VSP setting which u mentioned so full backup succeded. but i didnt know how , why VSP relates to network issue. ??????

No oraphaned VSP files. 

backup NIC -- at auto set

any clue why <32> n <16> ???? in bpbkar logs

[Mark_Solutions]

Both say 10053 - that is a break in the link or a timeout - try increasing you client read timeouts (on the Media Server is most important)

[rookie11]

will do it n update