MHVTL - Linux Virtual Tape Library - Community Forums

error in writing data to tapes.

Classic

List

Threaded

12 messages Options

Albatross

error in writing data to tapes.

Hi,

I have installed NB 6.5 and mhvtl , everything seems to be working fine.... but when I started to backup its gonna fail....giving 96 error..

when taken backup, first tape is loaded successfully and data is written on it , But when it is out of space second tape should be loaded automatically which is not happening in case.

No matter How many times I tried no luck....

when search for error messages "no no tape loaded error " or 'I/O error" in mhvtl box..

Can anyone please help me out....

Cheers
Albatross..

Remove Ads

Mark Harvey

Re: error in writing data to tapes.

can you provide the syslog (/var/log/messages)

and the bptm log (/usr/openv/netbackup/logs/bptm/mmddyy.log)

Sent from my iPad

On Dec 2, 2011, at 20:31, "Albatross [via MHVTL - Linux Virtual Tape Library - Community Forums]"<[hidden email]> wrote:

Hi,

I have installed NB 6.5 and mhvtl , everything seems to be working fine.... but when I started to backup its gonna fail....giving 96 error..

when taken backup, first tape is loaded successfully and data is written on it , But when it is out of space second tape should be loaded automatically which is not happening in case.

No matter How many times I tried no luck....

when search for error messages "no no tape loaded error " or 'I/O error" in mhvtl box..

Can anyone please help me out....

Cheers
Albatross..

If you reply to this email, your message will be added to the discussion below:
http://mhvtl-linux-virtual-tape-library-community-forums.966029.n3.nabble.com/error-in-writing-data-to-tapes-tp3554173p3554173.html

To start a new topic under MHVTL - Linux Virtual Tape Library - Community Forums, email [hidden email]
To unsubscribe from MHVTL - Linux Virtual Tape Library - Community Forums, click here.
NAML

Albatross

Re: error in writing data to tapes.

Hi Mark ,

sorry for late reply, I have connected to windows 2k3 server with iscsi , the tape drives are detected and as told when I have started backup first tape is loading fine but when it ends it should take the second tape , its giving 84 error

here are logs

/var/log/messages

Dec 4 21:25:36 new-backup vtltape[4501]: spc_mode_sense: MODE SENSE (52122) **
Dec 4 21:25:36 new-backup kernel: mhvtl: CDB (52123) 16 bytes
Dec 4 21:25:36 new-backup kernel: 05 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Dec 4 21:25:36 new-backup vtltape[4501]: CDB (52123) 05 00 00 00 00 00
Dec 4 21:25:36 new-backup vtltape[4501]: ssc_read_block_limits: Read block limits (52123) **
Dec 4 21:25:36 new-backup kernel: mhvtl: CDB (52124) 16 bytes
Dec 4 21:25:36 new-backup kernel: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Dec 4 21:25:36 new-backup vtltape[4501]: CDB (52124) 00 00 00 00 00 00
Dec 4 21:25:36 new-backup vtltape[4501]: mkSenseBuf: SENSE [Key/ASC/ASCQ] [02 3a 00]
Dec 4 21:25:36 new-backup vtltape[4501]: ssc_tur: Test Unit Ready (52124) ** : No, No tape loaded

and in backupwindow i have these in detail tab in activity monitor

12/4/2011 11:47:40 PM - requesting resource veritas75.NBU_POLICY.MAXJOBS.test
12/4/2011 11:47:40 PM - granted resource veritas75.NBU_CLIENT.MAXJOBS.veritas75
12/4/2011 11:47:40 PM - granted resource veritas75.NBU_POLICY.MAXJOBS.test
12/4/2011 11:47:40 PM - granted resource 1004L4
12/4/2011 11:47:40 PM - granted resource IBM.ULT3580-TD4.001
12/4/2011 11:47:40 PM - granted resource veritas75-hcart-robot-tld-0
12/4/2011 11:47:40 PM - estimated 0 Kbytes needed
12/4/2011 11:47:40 PM - Info nbjm(pid=3224) started backup (backupid=veritas75_1323022660) job for client veritas75, policy test, schedule full on storage unit veritas75-hcart-robot-tld-0
12/4/2011 11:47:40 PM - started
12/4/2011 11:47:41 PM - Info bpbrm(pid=3960) veritas75 is the host to backup data from
12/4/2011 11:47:41 PM - Info bpbrm(pid=3960) reading file list from client
12/4/2011 11:47:41 PM - started process bpbrm (3960)
12/4/2011 11:47:41 PM - connecting
12/4/2011 11:47:42 PM - Info bpbrm(pid=3960) starting bpbkar32 on client
12/4/2011 11:47:42 PM - connected; connect time: 00:00:01
12/4/2011 11:47:45 PM - Info bpbkar32(pid=188) Backup started
12/4/2011 11:47:45 PM - Info bptm(pid=3448) start
12/4/2011 11:47:46 PM - Info bptm(pid=3448) using 65536 data buffer size
12/4/2011 11:47:46 PM - Info bptm(pid=3448) setting receive network buffer to 263168 bytes
12/4/2011 11:47:46 PM - Info bptm(pid=3448) using 30 data buffers
12/4/2011 11:47:46 PM - Info bptm(pid=3448) start backup
12/4/2011 11:47:46 PM - Info bptm(pid=3448) Waiting for mount of media id 1004L4 (copy 1) on server veritas75.
12/4/2011 11:47:46 PM - mounting 1004L4
12/4/2011 11:47:55 PM - Info bptm(pid=3448) media id 1004L4 mounted on drive index 3, drivepath {3,0,0,5}, drivename IBM.ULT3580-TD4.001, copy 1
12/4/2011 11:47:55 PM - mounted; mount time: 00:00:09
12/4/2011 11:47:55 PM - positioning 1004L4 to file 1
12/4/2011 11:47:55 PM - positioned 1004L4; position time: 00:00:00
12/4/2011 11:47:55 PM - begin writing
12/4/2011 11:47:58 PM - Info bpbkar32(pid=188) change journal NOT enabled for <C:\>
12/4/2011 11:58:03 PM - Error bptm(pid=3448) FREEZING media id 1004L4, too many data blocks written, check tape/driver block size configuration
12/4/2011 11:58:03 PM - Info bptm(pid=3448) EXITING with status 84 <----------
12/4/2011 11:58:04 PM - Info bpbkar32(pid=188) bpbkar waited 5799 times for empty buffer, delayed 17190 times.
12/4/2011 11:58:06 PM - end writing; write time: 00:10:11
12/4/2011 11:58:06 PM - Info nbjm(pid=3224) starting backup job (jobid=5) for client veritas75, policy test, schedule full
12/4/2011 11:58:08 PM - begin Delete Snapshot
media write error(84)
12/4/2011 11:58:14 PM - Info bpfis(pid=2160) Backup started
12/4/2011 11:58:14 PM - Critical bpbrm(pid=3036) from client veritas75: cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.veritas75_1323022660.1.0
12/4/2011 11:58:14 PM - Info bpfis(pid=2160) done. status: 0
12/4/2011 11:58:14 PM - end Delete Snapshot; elapsed time: 00:00:06

Help me out....

Cheers....
Albatross

Mark Harvey

Re: error in writing data to tapes.

I can't say I've tried 7.5 with mhvtl on Windows yet.

Is this NetBackup beta1 or beta2 ?

Sent from my iPad

On Dec 5, 2011, at 5:49, "Albatross [via MHVTL - Linux Virtual Tape Library - Community Forums]"<[hidden email]> wrote:

Hi Mark ,

sorry for late reply, I have connected to windows 2k3 server with iscsi , the tape drives are detected and as told when I have started backup first tape is loading fine but when it ends it should take the second tape , its giving 84 error

here are logs

/var/log/messages

Dec 4 21:25:36 new-backup vtltape[4501]: spc_mode_sense: MODE SENSE (52122) **
Dec 4 21:25:36 new-backup kernel: mhvtl: CDB (52123) 16 bytes
Dec 4 21:25:36 new-backup kernel: 05 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Dec 4 21:25:36 new-backup vtltape[4501]: CDB (52123) 05 00 00 00 00 00
Dec 4 21:25:36 new-backup vtltape[4501]: ssc_read_block_limits: Read block limits (52123) **
Dec 4 21:25:36 new-backup kernel: mhvtl: CDB (52124) 16 bytes
Dec 4 21:25:36 new-backup kernel: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
Dec 4 21:25:36 new-backup vtltape[4501]: CDB (52124) 00 00 00 00 00 00
Dec 4 21:25:36 new-backup vtltape[4501]: mkSenseBuf: SENSE [Key/ASC/ASCQ] [02 3a 00]
Dec 4 21:25:36 new-backup vtltape[4501]: ssc_tur: Test Unit Ready (52124) ** : No, No tape loaded

and in backupwindow i have these in detail tab in activity monitor

12/4/2011 11:47:40 PM - requesting resource veritas75.NBU_POLICY.MAXJOBS.test
12/4/2011 11:47:40 PM - granted resource veritas75.NBU_CLIENT.MAXJOBS.veritas75
12/4/2011 11:47:40 PM - granted resource veritas75.NBU_POLICY.MAXJOBS.test
12/4/2011 11:47:40 PM - granted resource 1004L4
12/4/2011 11:47:40 PM - granted resource IBM.ULT3580-TD4.001
12/4/2011 11:47:40 PM - granted resource veritas75-hcart-robot-tld-0
12/4/2011 11:47:40 PM - estimated 0 Kbytes needed
12/4/2011 11:47:40 PM - Info nbjm(pid=3224) started backup (backupid=veritas75_1323022660) job for client veritas75, policy test, schedule full on storage unit veritas75-hcart-robot-tld-0
12/4/2011 11:47:40 PM - started
12/4/2011 11:47:41 PM - Info bpbrm(pid=3960) veritas75 is the host to backup data from
12/4/2011 11:47:41 PM - Info bpbrm(pid=3960) reading file list from client
12/4/2011 11:47:41 PM - started process bpbrm (3960)
12/4/2011 11:47:41 PM - connecting
12/4/2011 11:47:42 PM - Info bpbrm(pid=3960) starting bpbkar32 on client
12/4/2011 11:47:42 PM - connected; connect time: 00:00:01
12/4/2011 11:47:45 PM - Info bpbkar32(pid=188) Backup started
12/4/2011 11:47:45 PM - Info bptm(pid=3448) start
12/4/2011 11:47:46 PM - Info bptm(pid=3448) using 65536 data buffer size
12/4/2011 11:47:46 PM - Info bptm(pid=3448) setting receive network buffer to 263168 bytes
12/4/2011 11:47:46 PM - Info bptm(pid=3448) using 30 data buffers
12/4/2011 11:47:46 PM - Info bptm(pid=3448) start backup
12/4/2011 11:47:46 PM - Info bptm(pid=3448) Waiting for mount of media id 1004L4 (copy 1) on server veritas75.
12/4/2011 11:47:46 PM - mounting 1004L4
12/4/2011 11:47:55 PM - Info bptm(pid=3448) media id 1004L4 mounted on drive index 3, drivepath {3,0,0,5}, drivename IBM.ULT3580-TD4.001, copy 1
12/4/2011 11:47:55 PM - mounted; mount time: 00:00:09
12/4/2011 11:47:55 PM - positioning 1004L4 to file 1
12/4/2011 11:47:55 PM - positioned 1004L4; position time: 00:00:00
12/4/2011 11:47:55 PM - begin writing
12/4/2011 11:47:58 PM - Info bpbkar32(pid=188) change journal NOT enabled for <C:\>
12/4/2011 11:58:03 PM - Error bptm(pid=3448) FREEZING media id 1004L4, too many data blocks written, check tape/driver block size configuration
12/4/2011 11:58:03 PM - Info bptm(pid=3448) EXITING with status 84 <----------
12/4/2011 11:58:04 PM - Info bpbkar32(pid=188) bpbkar waited 5799 times for empty buffer, delayed 17190 times.
12/4/2011 11:58:06 PM - end writing; write time: 00:10:11
12/4/2011 11:58:06 PM - Info nbjm(pid=3224) starting backup job (jobid=5) for client veritas75, policy test, schedule full
12/4/2011 11:58:08 PM - begin Delete Snapshot
media write error(84)
12/4/2011 11:58:14 PM - Info bpfis(pid=2160) Backup started
12/4/2011 11:58:14 PM - Critical bpbrm(pid=3036) from client veritas75: cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.veritas75_1323022660.1.0
12/4/2011 11:58:14 PM - Info bpfis(pid=2160) done. status: 0
12/4/2011 11:58:14 PM - end Delete Snapshot; elapsed time: 00:00:06

Help me out....

Cheers....
Albatross

If you reply to this email, your message will be added to the discussion below:
http://mhvtl-linux-virtual-tape-library-community-forums.966029.n3.nabble.com/error-in-writing-data-to-tapes-tp3554173p3559560.html

To start a new topic under MHVTL - Linux Virtual Tape Library - Community Forums, email [hidden email]
To unsubscribe from MHVTL - Linux Virtual Tape Library - Community Forums, click here.
NAML

Mark Harvey

Re: error in writing data to tapes.

I'm really going to have to get the bptm logs & syslog (/var/log/messages typically) during a backup.

Make sure bptm is logging at level 5 (check via Host Properties->Media Server->Logging)
Create the <install path>\VERITAS\NetBackup\logs\bptm directory.

Re-run a backup.

The syslog supplied earlier does not contain any of the SCSI op codes involved with positioning or would be associated with the actual 'Status 84' error. Please make sure you send in logs during the time of the backup and a few minutes afterwards along with the bptm logs (and job details too).

Depending on available time tomorrow, I'll test NBU 7.5 Beta 2 on Windows tomorrow.

Cheers

Albatross

Re: error in writing data to tapes.

HI ,

Its 7.5 beta1, The funny thing is I could even work out with 6.6 version on windows too....

cheers......
Albatross

Albatross

Re: error in writing data to tapes.

HI ,

Its 7.5 beta1, The funny thing is I could even work out with 6.5 version on windows 2k3 too....The same error Its not taking second tape by default.... Its giving 84 error...

cheers......
Albatross

Albatross

Re: error in writing data to tapes.

In reply to this post by Mark Harvey

Hi ,

I have been working on veritas 6.5 on windows, even in that I am unable to take the backup. when initiated backup its working fine, but when that tape is full, its not mounting another one automatically,

As said by you I have created btpm folder and verbose into 5 logging I attaching the text file please find the file in the attachment

bptm logs: bptmlogs_120611.log

Cheers
ALbatross..

Mark Harvey

Re: error in writing data to tapes.

OK, bptm logs show this error:

22:33:34.139 [1596.1852] <2> write_data: write of 65536 bytes indicated only 0 bytes were written, err = 1100
22:33:34.139 [1596.1852] <2> write_backup: write_data() returned, exit_status = 0, CINDEX = 0, TWIN_INDEX = 0, backup_status = -3
22:33:34.139 [1596.1852] <2> write_backup: tp = 6693562, stp = 5987718, et = 705844, mpx_total_kbytes[TWIN_INDEX = 0] = 530048
22:33:34.155 [1596.1852] <2> signal_parent: set bpbrm media ready event (pid = 2936)
22:33:34.155 [1596.1852] <2> io_ioctl: command (0)MTWEOF 1 from (bptm.c.20450) on drive index 2
22:33:35.389 [1596.1852] <2> io_ioctl: command (2)MTBSF 1 from (bptm.c.20480) on drive index 2
22:33:35.405 [1596.1852] <2> io_close: closing C:\Program Files\Veritas\NetBackup\db\media\tpreq\drive_IBM.ULT3580-TD4.000, from bptm.c.20511
22:33:35.405 [1596.1852] <2> write_backup: block position check: actual 8283, expected 8285
22:33:35.405 [1596.1852] <2> db_error_add_to_file: dberrorq.c:midnite = 1323109800
22:33:35.405 [1596.1852] <16> write_backup: FREEZING media id 1005L4, External event caused rewind during write, all data on media is lost
22:33:35.405 [1596.1852] <2> send_MDS_msg: DEVICE_STATUS 1 3 win-nb 1005L4 4000005 IBM.ULT3580-TD4.000 2000009 WRITE_ERROR 0 0
22:33:35.436 [1596.1852] <2> log_media_error: successfully wrote to error file - 12/06/11 22:33:35 1005L4 2 WRITE_ERROR IBM.ULT3580-TD4.000
22:33:35.436 [1596.1852] <2> send_MDS_msg: MEDIADB 1 3 1005L4 4000005 *NULL* 13 1323190299 1323190299 2147483647 0 0 0 0 9 1 0 9 1024 0 0 0
22:33:35.452 [1596.1852] <2> check_error_history: just tpunmount: called from bptm line 20562, EXIT_Status = 84

i.e.
22:33:34.139 [1596.1852] <2> write_data: write of 65536 bytes indicated only 0 bytes were written, err = 1100

C:\>net helpmsg 1100
The physical end of the tape has been reached.

So here we hit EOT..

Now write the final filemark on media and re-position:
22:33:34.155 [1596.1852] <2> io_ioctl: command (0)MTWEOF 1 from (bptm.c.20450) on drive index 2
22:33:35.389 [1596.1852] <2> io_ioctl: command (2)MTBSF 1 from (bptm.c.20480) on drive index 2

Then a position check:
22:33:35.405 [1596.1852] <2> write_backup: block position check: actual 8283, expected 8285

Which fails..

Any chance of the syslog (/var/log/messages) to go along with this backup ?

I'll attempt to reproduce today.

Cheers
Mark

Mark Harvey

Re: error in writing data to tapes.

Just as a sanity check.

Used the mhvtl-1.1 release and confirmed NetBackup 7.5Beta2 on SLES works without error.

Now to test/try a Windows setup..

Mark Harvey

Re: error in writing data to tapes.

OK, I'm having problems getting LTO drivers to work.. T10k drivers are automagically found on the Microsoft driver update site.

You seem to have drivers loading and semi-working so a couple of basic NBU things to check.
1. http://support.microsoft.com/kb/842411 (Disable TUR)
2. Confirm 'Removable Storage Manager' service is set to 'Disabled'... Not Manual is not good enough as it will start from time to time and conflicts with NetBackup device management.

If the above fails, can you change the drive types to T10000B and re-test.
You will need up change/update the barcodes in library_contents.XX from an L4/L5 suffix to TA suffix.

Cheers

Mark Harvey

Re: error in writing data to tapes.

See other reply regarding IBM tape drivers. Once I found the latest win2k3 32bit drivers, all problems in this post went away.

Sent from my iPhone

On 07/12/2011, at 16:15, "Mark Harvey [via MHVTL - Linux Virtual Tape Library - CommunityForums]" <[hidden email]> wrote:

OK, I'm having problems getting LTO drivers to work.. T10k drivers are automagically found on the Microsoft driver update site.

You seem to have drivers loading and semi-working so a couple of basic NBU things to check.
1. http://support.microsoft.com/kb/842411 (Disable TUR)
2. Confirm 'Removable Storage Manager' service is set to 'Disabled'... Not Manual is not good enough as it will start from time to time and conflicts with NetBackup device management.

If the above fails, can you change the drive types to T10000B and re-test.
You will need up change/update the barcodes in library_contents.XX from an L4/L5 suffix to TA suffix.

Cheers

If you reply to this email, your message will be added to the discussion below:
http://mhvtl-linux-virtual-tape-library-community-forums.966029.n3.nabble.com/error-in-writing-data-to-tapes-tp3554173p3566511.html

To start a new topic under MHVTL - Linux Virtual Tape Library - Community Forums, email [hidden email]
To unsubscribe from MHVTL - Linux Virtual Tape Library - Community Forums, click here.
NAML