cancel
Showing results for 
Search instead for 
Did you mean: 

NetBackup Backup2Disk (HPE StoreOnce) - image write failed - error 2060022: software error

Tobias_P_
Level 4

Hello,

I'm testing at the moment NB8.1, for a switch from DataProtector back to Veritas.

Unfortunately I' not able to get a simple file system backup running on a HPE StoreOnce.

I added a Storage Server with type "hp-StoreOnceCatalyst", created a storage unit and a simple backup policy. 

NB8.1 is installed on a HP DL server, where master and media server is on one host/server, it's just for testing!

First I tried the source-side deduplication, which works fine on DataProtector and StoreOnce, but I get some error messages, so I tried deduplication on media server (master), client-side is disabled. But I get also errors during the backup.

Feb 9, 2018 3:49:18 PM - Info nbjm (pid=6512) starting backup job (jobid=47) for client BACKUPCLIENT, policy Test-Backup, schedule Test-Backup
Feb 9, 2018 3:49:18 PM - Info nbjm (pid=6512) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=47, request id:{9F9D6F38-8664-47F2-A7A1-79BA65F3033B})
Feb 9, 2018 3:49:18 PM - requesting resource  Store_Tobi
Feb 9, 2018 3:49:18 PM - requesting resource  backupmaster.domain.de.NBU_CLIENT.MAXJOBS.BACKUPCLIENT
Feb 9, 2018 3:49:18 PM - requesting resource  backupmaster.domain.de.NBU_POLICY.MAXJOBS.Test-Backup
Feb 9, 2018 3:49:18 PM - granted resource  backupmaster.domain.de.NBU_CLIENT.MAXJOBS.BACKUPCLIENT
Feb 9, 2018 3:49:18 PM - granted resource  backupmaster.domain.de.NBU_POLICY.MAXJOBS.Test-Backup
Feb 9, 2018 3:49:18 PM - granted resource  MediaID=@aaaad;DiskVolume=Store_Tobi;DiskPool=Store_Tobi;Path=Store_Tobi;StorageServer=10.10.10.10;MediaServer=backupmaster.domain.de
Feb 9, 2018 3:49:18 PM - granted resource  Store_Tobi
Feb 9, 2018 3:49:18 PM - estimated 0 kbytes needed
Feb 9, 2018 3:49:18 PM - Info nbjm (pid=6512) started backup (backupid=BACKUPCLIENT_1518187758) job for client BACKUPCLIENT, policy Test-Backup, schedule Test-Backup on storage unit Store_Tobi
Feb 9, 2018 3:49:19 PM - started process bpbrm (pid=2832)
Feb 9, 2018 3:49:20 PM - connecting
Feb 9, 2018 3:49:20 PM - Info bpbrm (pid=2832) BACKUPCLIENT is the host to backup data from
Feb 9, 2018 3:49:20 PM - Info bpbrm (pid=2832) reading file list for client
Feb 9, 2018 3:49:21 PM - connected; connect time: 0:00:00
Feb 9, 2018 3:49:21 PM - Info bpbrm (pid=2832) starting bpbkar32 on client
Feb 9, 2018 3:49:21 PM - Info bpbkar32 (pid=4576) Backup started
Feb 9, 2018 3:49:21 PM - Info bpbkar32 (pid=4576) change time comparison:<disabled>
Feb 9, 2018 3:49:21 PM - Info bpbkar32 (pid=4576) archive bit processing:<enabled>
Feb 9, 2018 3:49:21 PM - Info bpbkar32 (pid=4576) not using change journal data for <D:\NetBackup_8.1_Win>: not enabled
Feb 9, 2018 3:49:21 PM - Info bptm (pid=10156) start
Feb 9, 2018 3:49:21 PM - Info bptm (pid=10156) using 262144 data buffer size
Feb 9, 2018 3:49:21 PM - Info bptm (pid=10156) setting receive network buffer to 1049600 bytes
Feb 9, 2018 3:49:21 PM - Info bptm (pid=10156) using 30 data buffers
Feb 9, 2018 3:49:22 PM - Info bptm (pid=10156) start backup
Feb 9, 2018 3:49:52 PM - Critical bptm (pid=10156) image write failed: error 2060022: software error
Feb 9, 2018 3:50:06 PM - Info bptm (pid=10156) EXITING with status 84 <----------
Feb 9, 2018 3:50:06 PM - Info backupmaster.domain.de (pid=10156) StorageServer=hp-StoreOnceCatalyst:10.10.10.10; Report=scanned: 0 KB, CR sent: 0 KB, dedup: 0.00%
Feb 9, 2018 3:50:08 PM - Info bpbkar32 (pid=4576) done. status: 84: media write error
Feb 9, 2018 3:50:08 PM - end writing
Feb 9, 2018 3:50:09 PM - Info bpbrm (pid=2184) Starting delete snapshot processing
Feb 9, 2018 3:50:11 PM - Info bpfis (pid=1976) Backup started
Feb 9, 2018 3:50:11 PM - Warning bpbrm (pid=2184) from client BACKUPCLIENT: cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.BACKUPCLIENT_1518187758.1.0
Feb 9, 2018 3:50:11 PM - Info bpfis (pid=1976) done. status: 4207
Feb 9, 2018 3:50:11 PM - Info bpfis (pid=1976) done. status: 4207: Could not fetch snapshot metadata or state files
media write error  (84)

I can see that a media is created on the StoreOnce, but it's empty.

I search for some days, but didn't find anything about it that helps to resolve this error.

Anyone an idea?

 

Thanks and best regards,

Tobias

13 REPLIES 13

Amol_Nair
Level 6
Employee

-Check if the license on HP-store once is valid and not expired.

Since the error reported are plugin errors at the time of writing data to Hp-Store once you would need to check with the plugin and catalyst logs created on the media server in picture.

 

On windows usually the path where these logs can be found is something like this 

>> C:\Program Files\Hewlett-Packard\OpenStorage\<plugin_version>\logs

Here is the 4.0 HP-storeonce plugin guide for reference if you would need to further increase the plugin debugging

https://support.hpe.com/hpsc/doc/public/display?docId=emr_na-c03616587

**Hope that you have verified basic connectivity from the media server to the hp-storeonce, the plugin version on the media server and the credentials added in NetBackup.

Hello and thanks for your feedback!

The StoreOnce is a hardware compliance, there is not really a license. Also there are some more stores that are still using for daily backup with Data Protector. So StoreOnce is working. Just added a new store for testing with NetBackup.

Im using the user and installation guide of the StoreOnce Catalyst Plugin in version 4.0.2, not 4.0.0., to configure the OST plugin.

Found two log files in C:\Program Files\Hewlett Packard Enterprise\StoreOnce\isvsupport\OST\logs

plugin.log

=                  Äôt¤2018-01-25 14:00:47.789156 (local 15:00) : ERROR : 8220_7680  : -1 : - : Global : ost::ost::write_image : Ln 486 : OST ost::ost::write_image : OSCPPException: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169) (in oscpp::CAStream::SeekToWriteBytesInternal:169).
2018-01-25 14:51:16.254659 (local 15:51) : ERROR : 8680_8432  : -1 : - : Global : ost::ost::write_image : Ln 486 : OST ost::ost::write_image : OSCPPException: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169) (in oscpp::CAStream::SeekToWriteBytesInternal:169).
2018-01-25 14:53:10.442603 (local 15:53) : ERROR : 8408_7348  : -1 : - : Global : ost::ost::write_image : Ln 486 : OST ost::ost::write_image : OSCPPException: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169) (in oscpp::CAStream::SeekToWriteBytesInternal:169).
2018-01-25 17:20:37.498938 (local 18:20) : ERROR : 2540_364  : -1 : - : Global : ost::ost::write_image : Ln 486 : OST ost::ost::write_image : OSCPPException: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169) (in oscpp::CAStream::SeekToWriteBytesInternal:169).
2018-01-29 07:12:38.493387 (local 08:12) : ERROR : 10104_2476  : -1 : - : Global : ost::ost::write_image : Ln 486 : OST ost::ost::write_image : OSCPPException: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169) (in oscpp::CAStream::SeekToWriteBytesInternal:169).
2018-02-12 07:12:38.718175 (local 08:12) : ERROR : 10092_2748  : -1 : - : Global : ost::ost::write_image : Ln 486 : OST ost::ost::write_image : OSCPPException: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169) (in oscpp::CAStream::SeekToWriteBytesInternal:169).

catalyst.log

kY                  Äôt¤2018-01-25 14:00:47.789156 (local 15:00) : ERROR : 8220_7680  : 1 : TD : 1552 : 10.10.10.10 : Ix : OST(MT) : osCltTrnPrv_TranslateProtocolStatusToLocalWithLogging : Ln 1074 : Protocol status 2604 mapped to local status -3204
2018-01-25 14:00:47.789156 (local 15:00) : ERROR : 8220_7680  : 1 : TD : 1552 : 10.10.10.10 : Ix : OST(MT) : osCltTrnData_ObjectSeekToWriteSections : Ln 2132 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:00:47.789156 (local 15:00) : ERROR : 8220_7680  : 1 : TD : 1552 : 10.10.10.10 : Ix : OST(MT) : osCltTrnDataPrv_LogErrorAndCalcReturnStatus : Ln 124 : returnStatus=-3204
2018-01-25 14:00:47.789156 (local 15:00) : ERROR : 8220_7680  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4034 : Failed to seek to write sections.
2018-01-25 14:00:47.789156 (local 15:00) : ERROR : 8220_7680  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4035 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:00:47.789156 (local 15:00) : ERROR : 8220_7680  : 1 : D : OST(MT) : osCltDataPrv_ObjectDataSessionStateMachine : Ln 407 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:00:47.789156 (local 15:00) : ERROR : 8220_7680  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2126 : Unable to seek to write (low bandwidth). Requested seek offset : 0, data buffer size : 2097152. Error : -3204
2018-01-25 14:00:47.789156 (local 15:00) : ERROR : 8220_7680  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2127 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:00:47.789156 (local 15:00) : ERROR : 8220_7680  : 1 : D : OST(MT) : osCltData_SeekToWriteBytesInternal : Ln 2353 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:00:47.789156 (local 15:00) : ERROR : 8220_7680  : 1 : +DCA : OST(MT) : oscpp::CAStream::SeekToWriteBytesInternal : Ln 169 : EXCEPTION: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169)
2018-01-25 14:51:16.239074 (local 15:51) : ERROR : 8680_8432  : 1 : TD : 1556 : 10.10.10.10 : Ix : OST(MT) : osCltTrnPrv_TranslateProtocolStatusToLocalWithLogging : Ln 1074 : Protocol status 2604 mapped to local status -3204
2018-01-25 14:51:16.239074 (local 15:51) : ERROR : 8680_8432  : 1 : TD : 1556 : 10.10.10.10 : Ix : OST(MT) : osCltTrnData_ObjectSeekToWriteSections : Ln 2132 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:51:16.239074 (local 15:51) : ERROR : 8680_8432  : 1 : TD : 1556 : 10.10.10.10 : Ix : OST(MT) : osCltTrnDataPrv_LogErrorAndCalcReturnStatus : Ln 124 : returnStatus=-3204
2018-01-25 14:51:16.239074 (local 15:51) : ERROR : 8680_8432  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4034 : Failed to seek to write sections.
2018-01-25 14:51:16.239074 (local 15:51) : ERROR : 8680_8432  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4035 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:51:16.239074 (local 15:51) : ERROR : 8680_8432  : 1 : D : OST(MT) : osCltDataPrv_ObjectDataSessionStateMachine : Ln 407 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:51:16.239074 (local 15:51) : ERROR : 8680_8432  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2126 : Unable to seek to write (low bandwidth). Requested seek offset : 0, data buffer size : 2097152. Error : -3204
2018-01-25 14:51:16.239074 (local 15:51) : ERROR : 8680_8432  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2127 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:51:16.239074 (local 15:51) : ERROR : 8680_8432  : 1 : D : OST(MT) : osCltData_SeekToWriteBytesInternal : Ln 2353 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:51:16.239074 (local 15:51) : ERROR : 8680_8432  : 1 : +DCA : OST(MT) : oscpp::CAStream::SeekToWriteBytesInternal : Ln 169 : EXCEPTION: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169)
2018-01-25 14:53:10.442603 (local 15:53) : ERROR : 8408_7348  : 1 : TD : 1736 : 10.10.10.10 : Ix : OST(MT) : osCltTrnPrv_TranslateProtocolStatusToLocalWithLogging : Ln 1074 : Protocol status 2604 mapped to local status -3204
2018-01-25 14:53:10.442603 (local 15:53) : ERROR : 8408_7348  : 1 : TD : 1736 : 10.10.10.10 : Ix : OST(MT) : osCltTrnData_ObjectSeekToWriteSections : Ln 2132 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:53:10.442603 (local 15:53) : ERROR : 8408_7348  : 1 : TD : 1736 : 10.10.10.10 : Ix : OST(MT) : osCltTrnDataPrv_LogErrorAndCalcReturnStatus : Ln 124 : returnStatus=-3204
2018-01-25 14:53:10.442603 (local 15:53) : ERROR : 8408_7348  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4034 : Failed to seek to write sections.
2018-01-25 14:53:10.442603 (local 15:53) : ERROR : 8408_7348  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4035 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:53:10.442603 (local 15:53) : ERROR : 8408_7348  : 1 : D : OST(MT) : osCltDataPrv_ObjectDataSessionStateMachine : Ln 407 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:53:10.442603 (local 15:53) : ERROR : 8408_7348  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2126 : Unable to seek to write (low bandwidth). Requested seek offset : 0, data buffer size : 2097152. Error : -3204
2018-01-25 14:53:10.442603 (local 15:53) : ERROR : 8408_7348  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2127 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:53:10.442603 (local 15:53) : ERROR : 8408_7348  : 1 : D : OST(MT) : osCltData_SeekToWriteBytesInternal : Ln 2353 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 14:53:10.442603 (local 15:53) : ERROR : 8408_7348  : 1 : +DCA : OST(MT) : oscpp::CAStream::SeekToWriteBytesInternal : Ln 169 : EXCEPTION: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169)
2018-01-25 17:20:37.498938 (local 18:20) : ERROR : 2540_364  : 1 : TD : 1604 : 10.10.10.10 : Ix : OST(MT) : osCltTrnPrv_TranslateProtocolStatusToLocalWithLogging : Ln 1074 : Protocol status 2604 mapped to local status -3204
2018-01-25 17:20:37.498938 (local 18:20) : ERROR : 2540_364  : 1 : TD : 1604 : 10.10.10.10 : Ix : OST(MT) : osCltTrnData_ObjectSeekToWriteSections : Ln 2132 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 17:20:37.498938 (local 18:20) : ERROR : 2540_364  : 1 : TD : 1604 : 10.10.10.10 : Ix : OST(MT) : osCltTrnDataPrv_LogErrorAndCalcReturnStatus : Ln 124 : returnStatus=-3204
2018-01-25 17:20:37.498938 (local 18:20) : ERROR : 2540_364  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4034 : Failed to seek to write sections.
2018-01-25 17:20:37.498938 (local 18:20) : ERROR : 2540_364  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4035 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 17:20:37.498938 (local 18:20) : ERROR : 2540_364  : 1 : D : OST(MT) : osCltDataPrv_ObjectDataSessionStateMachine : Ln 407 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 17:20:37.498938 (local 18:20) : ERROR : 2540_364  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2126 : Unable to seek to write (low bandwidth). Requested seek offset : 0, data buffer size : 2097152. Error : -3204
2018-01-25 17:20:37.498938 (local 18:20) : ERROR : 2540_364  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2127 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 17:20:37.498938 (local 18:20) : ERROR : 2540_364  : 1 : D : OST(MT) : osCltData_SeekToWriteBytesInternal : Ln 2353 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-25 17:20:37.498938 (local 18:20) : ERROR : 2540_364  : 1 : +DCA : OST(MT) : oscpp::CAStream::SeekToWriteBytesInternal : Ln 169 : EXCEPTION: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169)
2018-01-30 16:14:39.929924 (local 17:14) : ERROR : 9344_8928  : 1 : TD : 1588 : 10.10.10.10 : Ix : OST(MT) : osCltTrnPrv_TranslateProtocolStatusToLocalWithLogging : Ln 1074 : Protocol status 2604 mapped to local status -3204
2018-01-30 16:14:39.929924 (local 17:14) : ERROR : 9344_8928  : 1 : TD : 1588 : 10.10.10.10 : Ix : OST(MT) : osCltTrnData_ObjectSeekToWriteSections : Ln 2132 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-30 16:14:39.929924 (local 17:14) : ERROR : 9344_8928  : 1 : TD : 1588 : 10.10.10.10 : Ix : OST(MT) : osCltTrnDataPrv_LogErrorAndCalcReturnStatus : Ln 124 : returnStatus=-3204
2018-01-30 16:14:39.929924 (local 17:14) : ERROR : 9344_8928  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4034 : Failed to seek to write sections.
2018-01-30 16:14:39.929924 (local 17:14) : ERROR : 9344_8928  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4035 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-30 16:14:39.929924 (local 17:14) : ERROR : 9344_8928  : 1 : D : OST(MT) : osCltDataPrv_ObjectDataSessionStateMachine : Ln 407 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-30 16:14:39.929924 (local 17:14) : ERROR : 9344_8928  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2126 : Unable to seek to write (low bandwidth). Requested seek offset : 0, data buffer size : 2097152. Error : -3204
2018-01-30 16:14:39.929924 (local 17:14) : ERROR : 9344_8928  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2127 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-30 16:14:39.929924 (local 17:14) : ERROR : 9344_8928  : 1 : D : OST(MT) : osCltData_SeekToWriteBytesInternal : Ln 2353 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-30 16:14:39.929924 (local 17:14) : ERROR : 9344_8928  : 1 : +DCA : OST(MT) : oscpp::CAStream::SeekToWriteBytesInternal : Ln 169 : EXCEPTION: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169)
2018-01-30 16:25:06.424855 (local 17:25) : ERROR : 7808_9424  : 1 : TD : 1768 : 10.10.10.10 : Ix : OST(MT) : osCltTrnPrv_TranslateProtocolStatusToLocalWithLogging : Ln 1074 : Protocol status 2604 mapped to local status -3204
2018-01-30 16:25:06.424855 (local 17:25) : ERROR : 7808_9424  : 1 : TD : 1768 : 10.10.10.10 : Ix : OST(MT) : osCltTrnData_ObjectSeekToWriteSections : Ln 2132 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-30 16:25:06.424855 (local 17:25) : ERROR : 7808_9424  : 1 : TD : 1768 : 10.10.10.10 : Ix : OST(MT) : osCltTrnDataPrv_LogErrorAndCalcReturnStatus : Ln 124 : returnStatus=-3204
2018-01-30 16:25:06.424855 (local 17:25) : ERROR : 7808_9424  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4034 : Failed to seek to write sections.
2018-01-30 16:25:06.424855 (local 17:25) : ERROR : 7808_9424  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4035 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-30 16:25:06.424855 (local 17:25) : ERROR : 7808_9424  : 1 : D : OST(MT) : osCltDataPrv_ObjectDataSessionStateMachine : Ln 407 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-30 16:25:06.424855 (local 17:25) : ERROR : 7808_9424  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2126 : Unable to seek to write (low bandwidth). Requested seek offset : 0, data buffer size : 2097152. Error : -3204
2018-01-30 16:25:06.424855 (local 17:25) : ERROR : 7808_9424  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2127 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-30 16:25:06.424855 (local 17:25) : ERROR : 7808_9424  : 1 : D : OST(MT) : osCltData_SeekToWriteBytesInternal : Ln 2353 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-01-30 16:25:06.424855 (local 17:25) : ERROR : 7808_9424  : 1 : +DCA : OST(MT) : oscpp::CAStream::SeekToWriteBytesInternal : Ln 169 : EXCEPTION: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169)
2018-02-05 07:12:38.807186 (local 08:12) : ERROR : 9440_7844  : 1 : TD : 1588 : 10.10.10.10 : Ix : OST(MT) : osCltTrnPrv_TranslateProtocolStatusToLocalWithLogging : Ln 1074 : Protocol status 2604 mapped to local status -3204
2018-02-05 07:12:38.807186 (local 08:12) : ERROR : 9440_7844  : 1 : TD : 1588 : 10.10.10.10 : Ix : OST(MT) : osCltTrnData_ObjectSeekToWriteSections : Ln 2132 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-02-05 07:12:38.807186 (local 08:12) : ERROR : 9440_7844  : 1 : TD : 1588 : 10.10.10.10 : Ix : OST(MT) : osCltTrnDataPrv_LogErrorAndCalcReturnStatus : Ln 124 : returnStatus=-3204
2018-02-05 07:12:38.807186 (local 08:12) : ERROR : 9440_7844  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4034 : Failed to seek to write sections.
2018-02-05 07:12:38.807186 (local 08:12) : ERROR : 9440_7844  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4035 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-02-05 07:12:38.807186 (local 08:12) : ERROR : 9440_7844  : 1 : D : OST(MT) : osCltDataPrv_ObjectDataSessionStateMachine : Ln 407 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-02-05 07:12:38.807186 (local 08:12) : ERROR : 9440_7844  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2126 : Unable to seek to write (low bandwidth). Requested seek offset : 0, data buffer size : 2097152. Error : -3204
2018-02-05 07:12:38.807186 (local 08:12) : ERROR : 9440_7844  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2127 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-02-05 07:12:38.807186 (local 08:12) : ERROR : 9440_7844  : 1 : D : OST(MT) : osCltData_SeekToWriteBytesInternal : Ln 2353 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-02-05 07:12:38.807186 (local 08:12) : ERROR : 9440_7844  : 1 : +DCA : OST(MT) : oscpp::CAStream::SeekToWriteBytesInternal : Ln 169 : EXCEPTION: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169)
2018-02-12 07:12:38.718175 (local 08:12) : ERROR : 10092_2748  : 1 : TD : 1724 : 10.10.10.10 : Ix : OST(MT) : osCltTrnPrv_TranslateProtocolStatusToLocalWithLogging : Ln 1074 : Protocol status 2604 mapped to local status -3204
2018-02-12 07:12:38.718175 (local 08:12) : ERROR : 10092_2748  : 1 : TD : 1724 : 10.10.10.10 : Ix : OST(MT) : osCltTrnData_ObjectSeekToWriteSections : Ln 2132 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-02-12 07:12:38.718175 (local 08:12) : ERROR : 10092_2748  : 1 : TD : 1724 : 10.10.10.10 : Ix : OST(MT) : osCltTrnDataPrv_LogErrorAndCalcReturnStatus : Ln 124 : returnStatus=-3204
2018-02-12 07:12:38.718175 (local 08:12) : ERROR : 10092_2748  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4034 : Failed to seek to write sections.
2018-02-12 07:12:38.718175 (local 08:12) : ERROR : 10092_2748  : 1 : TMD : OST(MT) : osCltTeamData_ObjectSeekToWriteSections : Ln 4035 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-02-12 07:12:38.718175 (local 08:12) : ERROR : 10092_2748  : 1 : D : OST(MT) : osCltDataPrv_ObjectDataSessionStateMachine : Ln 407 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-02-12 07:12:38.718175 (local 08:12) : ERROR : 10092_2748  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2126 : Unable to seek to write (low bandwidth). Requested seek offset : 0, data buffer size : 2097152. Error : -3204
2018-02-12 07:12:38.718175 (local 08:12) : ERROR : 10092_2748  : 1 : D : OST(MT) : osCltDataPrv_SeekToWriteBytesLocked : Ln 2127 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-02-12 07:12:38.718175 (local 08:12) : ERROR : 10092_2748  : 1 : D : OST(MT) : osCltData_SeekToWriteBytesInternal : Ln 2353 : GOTO ReturnStatus = -3204 (OSCLT_ERR_INVALID_BUFFER_SIZE).
2018-02-12 07:12:38.718175 (local 08:12) : ERROR : 10092_2748  : 1 : +DCA : OST(MT) : oscpp::CAStream::SeekToWriteBytesInternal : Ln 169 : EXCEPTION: CA seek to write failed : OSCLT_ERR_INVALID_BUFFER_SIZE [-3204] (from:oscpp::CAStream::SeekToWriteBytesInternal@169)

So it seems I have a problem with the buffersize, but it's all default.

At StoreOnce side, I can just create a store, that doesn't mind, what I'll save in it. So it must be a NetBackup setting, or not?

**Connection is available, NB tries to write something into the store. A user/password is not configured at StoreOnce side, so I just gave a dummy username and password, as described in the guide.

 (need to cut the logs, message was over 20k chars)

Any ideas?

Thanks!

Best regards,

Tobias

The buffer related messages that you see could be the default buffer values which are picked from the hp store once plugin.cfg file in case of low bandwidth or no transfer of data from the media server to it. We can see the low bandwidth message reported in the catayst snippet that you have shared. 20 MB is the default buffer value that is used in case of low bandwidth mode from hp side. 

----------------------

Unable to seek to write (low bandwidth). Requested seek offset : 0, data buffer size : 2097152. Error : -3204

----------------------

If you could share the complete catalyst log file as an attachment I would like to see if apart from these are any mesages reported.

The snippets shared above appear to be more of a stripped down version of only the errors. I would like to take a look at the other messages reported as well, not just the lines with error in it. 

By default the catalyst logging level is set to ERROR, for further debugging you may be asked to increase the logging level further.

So if there are no issues with the licenses on the Hp-store once side then, I have a feeling that there may be no data being transferred from the client to the media server and then to the storage and in-turn the job errorring out.

Along with this could you also run the below command from the media server - backupmaster.domain.de

>> bpstsinfo -serverinfo -storage_server 10.10.10.10 -stype hp-StoreOnceCatalyst

 

Hello and thank you very much for your help!

I've attached the logs, set log level from error to debug and restartet NetBackup Services.

catalyst_.log and plugin_.log are the old log, plugin.log and catalyst.log the new ones. I also used the media/master server itself for a simple file backup.

 

Best regards,
Tobias

Looking through the plugin logs it seems that the APPLICATION_LOGLEVEL and CATALYST_LOGLEVEL settings in the plugin.conf file are currently set to ERROR, this seem to be the reason why it is not dumping more information in it.

Could you change these 2  levels to either INFO or EXTENDED_DEBUG and then perform the test backup once again and then capture the catalyst and plugin logs. That should show us more information. Details regarding increasing the logging level in the plugin.conf file is documented in the plugin guide

Hello!

Strange, because I changed the log level to debug, but .. nevermind.

I repeated the steps, set to debug_ext and restarted all services, restarted the backup and saw that the configs were growing. Because the one is about 10MB, I zipped both and attached them. 

Thanks for your help!

Best regards,

Tobias

For some reason the timings in the plugin.log and the catalyst log do not match.. So your catalyst logs just have information regarding reading the properties and configuration across all the stores on the HP storeonce

The images failure reported in the plugin logs is around 11:34.. But the catalyst log shared starts after 11:39 so no information in the catalyst logs in regards to the image

The plugin log does show the below error reported
———————-
stsp_image_base<struct stsp_image>::tags : Ln 126 : OST object meta data is now dirty (image=itcsapshare2_1518780849_C1_HDR)
———————————-
Along with the above message we also see the message in regards to EOD (End of Data).. could be that the media server bptm process is not able to provide data to your ost device probably due to some connectivity issue

———————————-
stsp_image_handle_s::Write : Ln 283 : OST Write (object component) [offset=0,size=512]
2018-02-16 11:34:22.739954 (local 12:34) : EXTENDED DEBUG : 8340_8888 : -1 : - : Global : oscpp::CASplitter::eod : Ln 271 : ------------------------------ Entering oscpp::CASplitter::eod -----------------------------
2018-02-16 11:34:22.739954 (local 12:34) : EXTENDED DEBUG : 8340_8888 : -1 : - : Global : oscpp::CASplitter::eod : Ln 271 : ------------------------------ Leaving oscpp::CASplitter::eod -----------------------------
———————————

One more thing I would like to verify.. is the clock on Hp store once 1 hour ahead of the media server’s clock.?

I've checked both clocks, but they run with NTP synchron.

Today I restarted the StoreOnce, but it's still the same error message.

I also going through the guides again, but still no "luck".

 

Don't know if NetBackup would now be my first choice ..

Still thank you for your help!

 

Did you try a using a different plugin version on the media server.. If this is the latest plugin version then probably installing a lower version of the plugin?

And next question is did you reach out to HP support regarding this?

I'm not able to download a lower version than 4.0.2., but I'll follow your hint and now going to HP support.

Thanks for your help and time.

If I find a solution, I'll post it here.

Did you find the solution to it?

Marianne
Moderator
Moderator
Partner    VIP    Accredited Certified

@quaziashfaq 

The original poster @Tobias_P_  last visited here on 07-20-2018 09:13 AM.

If none of the advise in this discussion helped you, best to start a new discussion where you explain your issue, or, as per the last post - log a call with HP. 

Hi,

Sorry, we've decide not using NetBackup for many reasons ..

Good luck!