Video Screencast Help

Backup completed with 32 kb size on SAN & same has been completed with 300 Gb on LAN

Created: 19 Sep 2013 • Updated: 19 Sep 2013 | 1 comment

Here is the issue with media server backup happening through LAN media server (reflecting proper size). but happening with 32 kb backup size & successful status

Below are the steps followed

- when it completes with 32 kb i fired same policy using LAN storage unit which completed with proper size up to 300 GB

- no bpbkar log generated when fired on SAN & completed with 32 kb

- reconfigured tape drive on the media server & backup completed successfully but now & then it is observed that some time backup completes with proper size & some time with just 32 kb

though data resides & showing in backup path

- bpbkar -nocount /path logs will be generated very soon

- It is under observation need to find root cause

Job Details LAN job -

 

09/19/2013 02:57:45 - Info nbjm (pid=26166) starting backup job (jobid=230069) for client SMEDB1, policy DAKC_D_W_M_SMEDB1, schedule DAKC_D_SMEDB1
09/19/2013 02:57:45 - Info nbjm (pid=26166) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=230069, request id:{28C3B9C4-20A9-11E3-8B7B-002128FA0260})
09/19/2013 02:57:45 - requesting resource netback8-hcart2-robot-tld-2_MPX
09/19/2013 02:57:45 - requesting resource dakcmstnetbkp.NBU_CLIENT.MAXJOBS.SMEDB1
09/19/2013 02:57:45 - requesting resource dakcmstnetbkp.NBU_POLICY.MAXJOBS.DAKC_D_W_M_SMEDB1
09/19/2013 02:57:48 - Waiting for scan drive stop Drive021, Media server: netback8
09/19/2013 02:57:51 - granted resource  dakcmstnetbkp.NBU_CLIENT.MAXJOBS.SMEDB1
09/19/2013 02:57:51 - granted resource  dakcmstnetbkp.NBU_POLICY.MAXJOBS.DAKC_D_W_M_SMEDB1
09/19/2013 02:57:51 - granted resource  DW0402
09/19/2013 02:57:51 - granted resource  Drive021
09/19/2013 02:57:51 - granted resource  netback8-hcart2-robot-tld-2_MPX
09/19/2013 02:57:51 - estimated 467122063 kbytes needed
09/19/2013 02:57:51 - Info nbjm (pid=26166) started backup (backupid=SMEDB1_1379539671) job for client SMEDB1, policy DAKC_D_W_M_SMEDB1, schedule DAKC_D_SMEDB1 on storage unit netback8-hcart2-robot-tld-2_MPX
09/19/2013 02:57:52 - Info bpbrm (pid=6523) starting bptm
09/19/2013 02:57:52 - Info bpbrm (pid=6523) Started media manager using bpcd successfully
09/19/2013 02:57:52 - started process bpbrm (pid=6523)
09/19/2013 02:58:01 - Info bpbrm (pid=6523) SMEDB1 is the host to backup data from
09/19/2013 02:58:01 - Info bpbrm (pid=6523) telling media manager to start backup on client
09/19/2013 02:58:01 - Info bptm (pid=6525) using 262144 data buffer size
09/19/2013 02:58:01 - Info bptm (pid=6525) using 64 data buffers
09/19/2013 02:58:02 - Info bpbrm (pid=6523) spawning a brm child process
09/19/2013 02:58:02 - Info bpbrm (pid=6523) child pid: 6554
09/19/2013 02:58:02 - Info bptm (pid=6525) start backup
09/19/2013 02:58:02 - Info bptm (pid=6525) Waiting for mount of media id DW0402 (copy 1) on server netback8.
09/19/2013 02:58:02 - mounting DW0402
09/19/2013 02:58:03 - Info bpbrm (pid=6523) sending bpsched msg: CONNECTING TO CLIENT FOR SMEDB1_1379539671
09/19/2013 02:58:03 - Info bptm (pid=6553) setting receive network buffer to 262144 bytes
09/19/2013 02:58:03 - connecting
09/19/2013 02:58:04 - Info bpbrm (pid=6523) start bpbkar on client
09/19/2013 02:58:04 - Info bpbkar (pid=21103814) Backup started
09/19/2013 02:58:04 - Info bpbrm (pid=6523) Sending the file list to the client
09/19/2013 02:58:04 - Error bptm (pid=6525) error requesting media, TpErrno = Robot operation failed
09/19/2013 02:58:04 - Warning bptm (pid=6525) media id DW0402 load operation reported an error
09/19/2013 02:58:04 - connected; connect time: 0:00:00
09/19/2013 02:58:04 - current media DW0402 complete, requesting next media Any
09/19/2013 02:58:44 - Waiting for scan drive stop Drive021, Media server: netback8
09/19/2013 02:58:46 - granted resource  DM0658
09/19/2013 02:58:46 - granted resource  Drive021
09/19/2013 02:58:46 - granted resource  netback8-hcart2-robot-tld-2_MPX
09/19/2013 02:58:50 - Info bptm (pid=6525) Waiting for mount of media id DM0658 (copy 1) on server netback8.
09/19/2013 02:58:50 - end writing
09/19/2013 02:58:50 - mounting DM0658
09/19/2013 02:58:53 - Error bptm (pid=6525) error requesting media, TpErrno = Robot operation failed
09/19/2013 02:58:53 - Warning bptm (pid=6525) media id DM0658 load operation reported an error
09/19/2013 02:58:53 - current media DM0658 complete, requesting next media Any
09/19/2013 02:59:37 - Waiting for scan drive stop Drive021, Media server: netback8
09/19/2013 02:59:39 - granted resource  DM0542
09/19/2013 02:59:39 - granted resource  Drive021
09/19/2013 02:59:39 - granted resource  netback8-hcart2-robot-tld-2_MPX
09/19/2013 02:59:41 - Info bptm (pid=6525) Waiting for mount of media id DM0542 (copy 1) on server netback8.
09/19/2013 02:59:41 - end writing
09/19/2013 02:59:41 - mounting DM0542
09/19/2013 02:59:59 - Info bptm (pid=6525) media id DM0542 mounted on drive index 4, drivepath /dev/rmt/20cbn, drivename Drive021, copy 1
09/19/2013 02:59:59 - mounted DM0542; mount time: 0:00:18
09/19/2013 02:59:59 - positioning DM0542 to file 8
09/19/2013 03:00:33 - positioned DM0542; position time: 0:00:34
09/19/2013 03:00:33 - begin writing
09/19/2013 11:08:11 - Info bpbrm (pid=6523) media manager for backup id SMEDB1_1379539671 exited with status 0: the requested operation was successfully completed
09/19/2013 11:08:12 - end writing; write time: 8:07:39
the requested operation was successfully completed  (0)
09/19/2013 02:57:45 - Info nbjm (pid=26166) starting backup job (jobid=230069) for client SMEDB1, policy DAKC_D_W_M_SMEDB1, schedule DAKC_D_SMEDB1
09/19/2013 02:57:45 - Info nbjm (pid=26166) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=230069, request id:{28C3B9C4-20A9-11E3-8B7B-002128FA0260})
09/19/2013 02:57:45 - requesting resource netback8-hcart2-robot-tld-2_MPX
09/19/2013 02:57:45 - requesting resource dakcmstnetbkp.NBU_CLIENT.MAXJOBS.SMEDB1
09/19/2013 02:57:45 - requesting resource dakcmstnetbkp.NBU_POLICY.MAXJOBS.DAKC_D_W_M_SMEDB1
09/19/2013 02:57:48 - Waiting for scan drive stop Drive021, Media server: netback8
09/19/2013 02:57:51 - granted resource  dakcmstnetbkp.NBU_CLIENT.MAXJOBS.SMEDB1
09/19/2013 02:57:51 - granted resource  dakcmstnetbkp.NBU_POLICY.MAXJOBS.DAKC_D_W_M_SMEDB1
09/19/2013 02:57:51 - granted resource  DW0402
09/19/2013 02:57:51 - granted resource  Drive021
09/19/2013 02:57:51 - granted resource  netback8-hcart2-robot-tld-2_MPX
09/19/2013 02:57:51 - estimated 467122063 kbytes needed
09/19/2013 02:57:51 - Info nbjm (pid=26166) started backup (backupid=SMEDB1_1379539671) job for client SMEDB1, policy DAKC_D_W_M_SMEDB1, schedule DAKC_D_SMEDB1 on storage unit netback8-hcart2-robot-tld-2_MPX
09/19/2013 02:57:52 - Info bpbrm (pid=6523) starting bptm
09/19/2013 02:57:52 - Info bpbrm (pid=6523) Started media manager using bpcd successfully
09/19/2013 02:57:52 - started process bpbrm (pid=6523)
09/19/2013 02:58:01 - Info bpbrm (pid=6523) SMEDB1 is the host to backup data from
09/19/2013 02:58:01 - Info bpbrm (pid=6523) telling media manager to start backup on client
09/19/2013 02:58:01 - Info bptm (pid=6525) using 262144 data buffer size
09/19/2013 02:58:01 - Info bptm (pid=6525) using 64 data buffers
09/19/2013 02:58:02 - Info bpbrm (pid=6523) spawning a brm child process
09/19/2013 02:58:02 - Info bpbrm (pid=6523) child pid: 6554
09/19/2013 02:58:02 - Info bptm (pid=6525) start backup
09/19/2013 02:58:02 - Info bptm (pid=6525) Waiting for mount of media id DW0402 (copy 1) on server netback8.
09/19/2013 02:58:02 - mounting DW0402
09/19/2013 02:58:03 - Info bpbrm (pid=6523) sending bpsched msg: CONNECTING TO CLIENT FOR SMEDB1_1379539671
09/19/2013 02:58:03 - Info bptm (pid=6553) setting receive network buffer to 262144 bytes
09/19/2013 02:58:03 - connecting
09/19/2013 02:58:04 - Info bpbrm (pid=6523) start bpbkar on client
09/19/2013 02:58:04 - Info bpbkar (pid=21103814) Backup started
09/19/2013 02:58:04 - Info bpbrm (pid=6523) Sending the file list to the client
09/19/2013 02:58:04 - Error bptm (pid=6525) error requesting media, TpErrno = Robot operation failed
09/19/2013 02:58:04 - Warning bptm (pid=6525) media id DW0402 load operation reported an error
09/19/2013 02:58:04 - connected; connect time: 0:00:00
09/19/2013 02:58:04 - current media DW0402 complete, requesting next media Any
09/19/2013 02:58:44 - Waiting for scan drive stop Drive021, Media server: netback8
09/19/2013 02:58:46 - granted resource  DM0658
09/19/2013 02:58:46 - granted resource  Drive021
09/19/2013 02:58:46 - granted resource  netback8-hcart2-robot-tld-2_MPX
09/19/2013 02:58:50 - Info bptm (pid=6525) Waiting for mount of media id DM0658 (copy 1) on server netback8.
09/19/2013 02:58:50 - end writing
09/19/2013 02:58:50 - mounting DM0658
09/19/2013 02:58:53 - Error bptm (pid=6525) error requesting media, TpErrno = Robot operation failed
09/19/2013 02:58:53 - Warning bptm (pid=6525) media id DM0658 load operation reported an error
09/19/2013 02:58:53 - current media DM0658 complete, requesting next media Any
09/19/2013 02:59:37 - Waiting for scan drive stop Drive021, Media server: netback8
09/19/2013 02:59:39 - granted resource  DM0542
09/19/2013 02:59:39 - granted resource  Drive021
09/19/2013 02:59:39 - granted resource  netback8-hcart2-robot-tld-2_MPX
09/19/2013 02:59:41 - Info bptm (pid=6525) Waiting for mount of media id DM0542 (copy 1) on server netback8.
09/19/2013 02:59:41 - end writing
09/19/2013 02:59:41 - mounting DM0542
09/19/2013 02:59:59 - Info bptm (pid=6525) media id DM0542 mounted on drive index 4, drivepath /dev/rmt/20cbn, drivename Drive021, copy 1
09/19/2013 02:59:59 - mounted DM0542; mount time: 0:00:18
09/19/2013 02:59:59 - positioning DM0542 to file 8
09/19/2013 03:00:33 - positioned DM0542; position time: 0:00:34
09/19/2013 03:00:33 - begin writing
09/19/2013 11:08:11 - Info bpbrm (pid=6523) media manager for backup id SMEDB1_1379539671 exited with status 0: the requested operation was successfully completed
09/19/2013 11:08:12 - end writing; write time: 8:07:39
the requested operation was successfully completed  (0)
 

 

JOB Details SAN Job with 32 kb size-

09/18/2013 07:57:24 - Info nbjm (pid=26166) starting backup job (jobid=229210) for client SMEDB1, policy DAKC_D_W_M_SMEDB1, schedule DAKC_D_SMEDB1
09/18/2013 07:57:24 - Info nbjm (pid=26166) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=229210, request id:{DA7BA532-2009-11E3-BFBC-002128FA0260})
09/18/2013 07:57:24 - requesting resource smedb1-hcart2-robot-tld-2
09/18/2013 07:57:24 - requesting resource dakcmstnetbkp.NBU_CLIENT.MAXJOBS.SMEDB1
09/18/2013 07:57:24 - requesting resource dakcmstnetbkp.NBU_POLICY.MAXJOBS.DAKC_D_W_M_SMEDB1
09/18/2013 07:57:25 - awaiting resource smedb1-hcart2-robot-tld-2. Waiting for resources.
          Reason: Drives are in use, Media server: smedb1,
          Robot Type(Number): TLD(2), Media ID: N/A, Drive Name: N/A,
          Volume Pool: DAKC_daily_pool, Storage Unit: smedb1-hcart2-robot-tld-2, Drive Scan Host: N/A,
          Disk Pool: N/A, Disk Volume: N/A
09/18/2013 08:06:01 - Waiting for scan drive stop Drive009, Media server: smedb1
09/18/2013 08:06:03 - granted resource  dakcmstnetbkp.NBU_CLIENT.MAXJOBS.SMEDB1
09/18/2013 08:06:03 - granted resource  dakcmstnetbkp.NBU_POLICY.MAXJOBS.DAKC_D_W_M_SMEDB1
09/18/2013 08:06:03 - granted resource  DW0136
09/18/2013 08:06:03 - granted resource  Drive009
09/18/2013 08:06:03 - granted resource  smedb1-hcart2-robot-tld-2
09/18/2013 08:06:03 - estimated 0 kbytes needed
09/18/2013 08:06:03 - Info nbjm (pid=26166) started backup (backupid=SMEDB1_1379471763) job for client SMEDB1, policy DAKC_D_W_M_SMEDB1, schedule DAKC_D_SMEDB1 on storage unit smedb1-hcart2-robot-tld-2
09/18/2013 08:06:04 - started process bpbrm (pid=16975036)
09/18/2013 08:06:05 - Info bpbrm (pid=16975036) SMEDB1 is the host to backup data from
09/18/2013 08:06:05 - Info bpbrm (pid=16975036) reading file list from client
09/18/2013 08:06:05 - connecting
09/18/2013 08:06:07 - Info bpbrm (pid=16975036) starting bpbkar on client
09/18/2013 08:06:07 - Info bpbkar (pid=60752134) Backup started
09/18/2013 08:06:07 - Info bpbrm (pid=16975036) bptm pid: 28311582
09/18/2013 08:06:07 - connected; connect time: 0:00:00
09/18/2013 08:06:08 - Info bptm (pid=28311582) start
09/18/2013 08:06:08 - Info bptm (pid=28311582) using 262144 data buffer size
09/18/2013 08:06:08 - Info bptm (pid=28311582) setting receive network buffer to 262144 bytes
09/18/2013 08:06:08 - Info bptm (pid=28311582) using 64 data buffers
09/18/2013 08:06:09 - Info bptm (pid=28311582) start backup
09/18/2013 08:06:09 - Info bptm (pid=28311582) backup child process is pid 31522908
09/18/2013 08:06:09 - Info bptm (pid=28311582) Waiting for mount of media id DW0136 (copy 1) on server smedb1.
09/18/2013 08:06:09 - mounting DW0136
09/18/2013 08:06:36 - Info bptm (pid=28311582) media id DW0136 mounted on drive index 0, drivepath /dev/rmt0.1, drivename Drive009, copy 1
09/18/2013 08:06:36 - mounted DW0136; mount time: 0:00:27
09/18/2013 08:06:36 - positioning DW0136 to file 25
09/18/2013 08:08:10 - positioned DW0136; position time: 0:01:34
09/18/2013 08:08:10 - begin writing
09/18/2013 08:08:11 - Info bptm (pid=28311582) waited for full buffer 0 times, delayed 0 times
09/18/2013 08:08:16 - Info bptm (pid=28311582) EXITING with status 0 <----------
09/18/2013 08:08:16 - Info bpbrm (pid=16975036) validating image for client SMEDB1
09/18/2013 08:08:16 - Info bpbkar (pid=60752134) done. status: 0: the requested operation was successfully completed
09/18/2013 08:08:16 - end writing; write time: 0:00:06
the requested operation was successfully completed  (0)
 

Thanks

 

 

 

 

Operating Systems:
Discussion Filed Under:

Comments 1 CommentJump to latest comment

Marianne's picture

We can see that the SAN backup is starting bpbkar :

09/18/2013 08:06:07 - Info bpbrm (pid=16975036) starting bpbkar on client

So, it seems totally weird that nothing is logged in bpbkar - almost as if it is backing up another client?

Please check hosts file on SMEDB1 - there may be an entry for smedb1 and another entry for SMEDB1 with a different IP address.

Why are you using hostnames in uppercase and lower case? They are not the same - Unix as well as NBU is case sensitive.

 

Supporting Storage Foundation and VCS on Unix and Windows as well as NetBackup on Unix and Windows
Handy NBU Links