NBU 7.5 - File system beeing backup up twice in the same schedule
Client: Centos 5.4 x86_64
Server: Solaris 10 Sparc
Both: NBU 7.5.0.4
Backup policy (standard):
- ALL_LOCAL_DRIVES
- Follow NFS / Cross Mountpoints are unchecked.
- Allow multiple data strerams checked
On client:
- /usr/openv/netbackup/include_list: /rawdata
- /usr/openv/netbackup/exclude_list: /*
Problem:
NBU starts two jobs for the same file system /rawdata and finishes both jobs separately. There are several clients in the policy so I'll have to use the ALL_LOCAL_DRIVES directive...
Detaills - job 1:
24.jan.2013 06:00:22 - Info nbjm (pid=19595) starting backup job (jobid=48509) for client nrsk, policy UNIX-SERVERS, schedule FULL
24.jan.2013 06:00:22 - estimated 625900838 kbytes needed
24.jan.2013 06:00:22 - Info nbjm (pid=19595) started backup (backupid=nrsk_1359003622) job for client nrsk, policy UNIX-SERVERS, schedule FULL on storage unit hegre-hcart2-robot-tld-0
24.jan.2013 06:00:23 - started process bpbrm (pid=6595)
24.jan.2013 06:00:24 - Info bpbrm (pid=6595) nrsk is the host to backup data from
24.jan.2013 06:00:24 - Info bpbrm (pid=6595) reading file list from client
24.jan.2013 06:00:24 - connecting
24.jan.2013 06:00:25 - Info bpbrm (pid=6595) starting bpbkar on client
24.jan.2013 06:00:25 - Info bpbkar (pid=23169) Backup started
24.jan.2013 06:00:25 - Info bpbrm (pid=6595) bptm pid: 6615
24.jan.2013 06:00:25 - connected; connect time: 0:00:00
24.jan.2013 06:00:26 - Info bptm (pid=6615) start
24.jan.2013 06:00:27 - Info bptm (pid=6615) using 65536 data buffer size
24.jan.2013 06:00:27 - Info bptm (pid=6615) using 30 data buffers
24.jan.2013 06:00:27 - Info bptm (pid=6615) start backup
24.jan.2013 06:00:27 - Info bptm (pid=6615) backup child process is pid 6627
24.jan.2013 06:00:27 - Info bptm (pid=6615) Waiting for mount of media id A00018 (copy 1) on server hegre.
24.jan.2013 06:00:27 - mounting A00018
24.jan.2013 06:02:06 - Info bptm (pid=6615) media id A00018 mounted on drive index 1, drivepath /dev/rmt/4cbn, drivename IBM.ULTRIUM-HH5.001, copy 1
24.jan.2013 06:02:06 - mounted A00018; mount time: 0:01:39
24.jan.2013 06:02:06 - positioning A00018 to file 3
24.jan.2013 06:03:19 - positioned A00018; position time: 0:01:13
24.jan.2013 06:03:19 - begin writing
24.jan.2013 09:02:44 - Info bptm (pid=6615) waited for full buffer 482632 times, delayed 525891 times
24.jan.2013 09:02:52 - Info bptm (pid=6615) EXITING with status 0 <----------
24.jan.2013 09:02:52 - Info bpbrm (pid=6595) validating image for client nrsk
24.jan.2013 09:02:54 - Info bpbkar (pid=23169) done. status: 0: the requested operation was successfully completed
24.jan.2013 09:02:54 - end writing; write time: 2:59:35
the requested operation was successfully completed (0)
Details - job 2:
24.jan.2013 06:00:23 - Info nbjm (pid=19595) starting backup job (jobid=48510) for client nrsk, policy UNIX-SERVERS, schedule FULL
24.jan.2013 06:00:23 - Info nbjm (pid=19595) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=48510, request id:{F5AC0C66-65E2-11E2-9A79-00212834954E})
24.jan.2013 06:00:23 - requesting resource Any
24.jan.2013 06:00:23 - requesting resource hegre.NBU_CLIENT.MAXJOBS.nrsk
24.jan.2013 06:00:23 - requesting resource hegre.NBU_POLICY.MAXJOBS.UNIX-SERVERS
24.jan.2013 06:00:23 - awaiting resource Any. No drives are available.
24.jan.2013 09:26:31 - granted resource hegre.NBU_CLIENT.MAXJOBS.nrsk
24.jan.2013 09:26:31 - granted resource hegre.NBU_POLICY.MAXJOBS.UNIX-SERVERS
24.jan.2013 09:26:31 - granted resource A00068
24.jan.2013 09:26:31 - granted resource IBM.ULTRIUM-HH5.000
24.jan.2013 09:26:31 - granted resource hegre-hcart2-robot-tld-0
24.jan.2013 09:26:32 - estimated 626488281 kbytes needed
24.jan.2013 09:26:32 - Info nbjm (pid=19595) started backup (backupid=nrsk_1359015991) job for client nrsk, policy UNIX-SERVERS, schedule FULL on storage unit hegre-hcart2-robot-tld-0
24.jan.2013 09:26:33 - Info bpbrm (pid=15331) nrsk is the host to backup data from
24.jan.2013 09:26:33 - Info bpbrm (pid=15331) reading file list from client
24.jan.2013 09:26:33 - started process bpbrm (pid=15331)
24.jan.2013 09:26:33 - connecting
24.jan.2013 09:26:35 - Info bpbrm (pid=15331) starting bpbkar on client
24.jan.2013 09:26:35 - Info bpbkar (pid=25550) Backup started
24.jan.2013 09:26:35 - Info bpbrm (pid=15331) bptm pid: 15333
24.jan.2013 09:26:35 - connected; connect time: 0:00:00
24.jan.2013 09:26:36 - Info bptm (pid=15333) start
24.jan.2013 09:26:36 - Info bptm (pid=15333) using 65536 data buffer size
24.jan.2013 09:26:36 - Info bptm (pid=15333) using 30 data buffers
24.jan.2013 09:26:36 - Info bptm (pid=15333) start backup
24.jan.2013 09:26:36 - Info bptm (pid=15333) backup child process is pid 15336
24.jan.2013 09:26:36 - Info bptm (pid=15333) media id A00068 mounted on drive index 0, drivepath /dev/rmt/5cbn, drivename IBM.ULTRIUM-HH5.000, copy 1
24.jan.2013 09:26:36 - mounted A00068
24.jan.2013 09:26:36 - positioning A00068 to file 5
24.jan.2013 09:26:42 - positioned A00068; position time: 0:00:06
24.jan.2013 09:26:42 - begin writing
24.jan.2013 12:47:25 - Info bptm (pid=15333) waited for full buffer 573145 times, delayed 616927 times
24.jan.2013 12:47:32 - Info bptm (pid=15333) EXITING with status 0 <----------
24.jan.2013 12:47:33 - Info bpbrm (pid=15331) validating image for client nrsk
24.jan.2013 12:47:34 - Info bpbkar (pid=25550) done. status: 0: the requested operation was successfully completed
24.jan.2013 12:47:34 - end writing; write time: 3:20:52
the requested operation was successfully completed (0)
Any ideas why??
BR,
Nils
The trouble is Allow multiple data streams is selected in the Policy so the job starts with two streams and then each stream goes through the include_list/exclude_list on the client and both streams end up pointing to the same filesystem (/rawdata).