02-09-2018 07:03 AM
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
02-09-2018 08:50 AM
-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.
02-12-2018 05:21 AM
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
02-13-2018 07:16 AM
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
02-14-2018 06:24 AM - edited 02-14-2018 06:26 AM
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
02-15-2018 10:29 AM - edited 02-15-2018 10:40 AM
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
02-16-2018 03:50 AM
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
02-18-2018 09:31 PM
02-19-2018 07:54 AM
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!
02-19-2018 09:30 AM
02-20-2018 12:42 AM
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.
09-02-2020 06:24 PM
Did you find the solution to it?
09-02-2020 11:42 PM
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.
09-03-2020 06:13 AM
Hi,
Sorry, we've decide not using NetBackup for many reasons ..
Good luck!