Bad performance with Netbackup Accelerator
Hello all,
Netbackup server 7.5.0.6 running on RedHat with accelerator enabled. Media server on Netbackup appliance 5220. Dedup done on media server.
We are taking in backup a Windows 2003 File server with a lot of small files (13 millions files on drive W).
Backup is running until 16:00 hours ! We had expected better performance with accelerator.... Using multiplexing between drives does not solve the problem.
Does anybody have the same problem ? Any idea to solve this issue.
Thanks.
Pierre.
09/10/2013 17:09:45 - Info nbjm (pid=16151) starting backup job (jobid=248096) for client vsrfil44.xxx.xxxxx.xxx, policy nbb_gold_corplan_fileserver_CLUFIL40_VSRFIL44, schedule Daily_Full
09/10/2013 17:09:45 - Info nbjm (pid=16151) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=248096, request id:{07461EA6-1A2B-11E3-B213-06D4DB52B228})
09/10/2013 17:09:45 - requesting resource stu_disk_nbuacorp22
09/10/2013 17:09:45 - requesting resource nbumas01.nammo.local.NBU_CLIENT.MAXJOBS.vsrfil44.xxx.xxxxx.xxx
09/10/2013 17:09:45 - requesting resource nbumas01.nammo.local.NBU_POLICY.MAXJOBS.nbb_gold_corplan_fileserver_CLUFIL40_VSRFIL44
09/10/2013 17:09:46 - granted resource nbumas01.nammo.local.NBU_CLIENT.MAXJOBS.vsrfil44.xxx.xxxxx.xxx
09/10/2013 17:09:46 - granted resource nbumas01.nammo.local.NBU_POLICY.MAXJOBS.nbb_gold_corplan_fileserver_CLUFIL40_VSRFIL44
09/10/2013 17:09:46 - granted resource MediaID=@aaaap;DiskVolume=PureDiskVolume;DiskPool=dp_disk_nbuacorp22;Path=PureDiskVolume;StorageServer=nbuacorp22;MediaServer=nbuacorp22
09/10/2013 17:09:46 - granted resource stu_disk_nbuacorp22
09/10/2013 17:09:46 - estimated 0 kbytes needed
09/10/2013 17:09:46 - Info nbjm (pid=16151) started backup (backupid=vsrfil44.xxx.xxxxx.xxx_1378825786) job for client vsrfil44.xxx.xxxxx.xxx, policy nbb_gold_corplan_fileserver_CLUFIL40_VSRFIL44, schedule Daily_Full on storage unit stu_disk_nbuacorp22
09/10/2013 17:09:47 - started process bpbrm (pid=21883)
09/10/2013 17:09:49 - Info bpbrm (pid=21883) vsrfil44.xxx.xxxxx.xxx is the host to backup data from
09/10/2013 17:09:49 - Info bpbrm (pid=21883) reading file list from client
09/10/2013 17:09:50 - Info bpbrm (pid=21883) accelerator enabled
09/10/2013 17:11:45 - connecting
09/10/2013 17:11:46 - Info bpbrm (pid=21883) starting bpbkar on client
09/10/2013 17:11:46 - connected; connect time: 0:00:00
09/10/2013 17:11:55 - Info bpbkar (pid=5400) Backup started
09/10/2013 17:11:55 - Info bpbrm (pid=21883) bptm pid: 21957
09/10/2013 17:11:56 - Info bptm (pid=21957) start
09/10/2013 17:11:56 - Info bptm (pid=21957) using 262144 data buffer size
09/10/2013 17:11:56 - Info bptm (pid=21957) using 30 data buffers
09/10/2013 17:11:57 - Info bptm (pid=21957) start backup
09/10/2013 17:12:03 - Info bptm (pid=21957) backup child process is pid 22009
09/10/2013 17:12:03 - begin writing
09/10/2013 17:13:24 - Info bpbkar (pid=5400) change journal NOT enabled for <O:\>
09/10/2013 17:31:33 - Info bpbkar (pid=5400) 186813 entries sent to bpdbm
09/10/2013 17:41:56 - Info bpbkar (pid=5400) 372077 entries sent to bpdbm
09/10/2013 17:50:42 - Info bpbkar (pid=5400) 557300 entries sent to bpdbm
09/10/2013 17:57:31 - Info bpbkar (pid=5400) 740791 entries sent to bpdbm
09/10/2013 18:07:25 - Info bpbkar (pid=5400) 927405 entries sent to bpdbm
09/10/2013 18:16:08 - Info bpbkar (pid=5400) 1112539 entries sent to bpdbm
09/10/2013 18:22:51 - Info bpbkar (pid=5400) 1296830 entries sent to bpdbm
09/10/2013 18:31:43 - Info bpbkar (pid=5400) 1482381 entries sent to bpdbm
09/10/2013 18:35:06 - Info bpbkar (pid=5400) 1559983 entries sent to bpdbm
09/10/2013 18:38:09 - Info bpbkar (pid=5400) 1631504 entries sent to bpdbm
09/10/2013 18:46:15 - Info bpbkar (pid=5400) 1786935 entries sent to bpdbm
09/10/2013 18:49:33 - Info bpbkar (pid=5400) 1887687 entries sent to bpdbm
09/10/2013 18:51:52 - Info bpbkar (pid=5400) 1960252 entries sent to bpdbm
09/10/2013 18:56:11 - Info bpbkar (pid=5400) 2072401 entries sent to bpdbm
09/10/2013 19:02:09 - Info bpbkar (pid=5400) 2197200 entries sent to bpdbm
09/10/2013 19:05:39 - Info bpbkar (pid=5400) 2293654 entries sent to bpdbm
09/10/2013 19:08:39 - Info bpbkar (pid=5400) 2356515 entries sent to bpdbm
09/10/2013 19:14:11 - Info bpbkar (pid=5400) 2434400 entries sent to bpdbm
09/10/2013 19:16:05 - Info bpbkar (pid=5400) 2488203 entries sent to bpdbm
09/10/2013 19:19:21 - Info bpbkar (pid=5400) 2572748 entries sent to bpdbm
09/10/2013 19:23:20 - Info bpbkar (pid=5400) 2679444 entries sent to bpdbm
09/10/2013 19:29:27 - Info bpbkar (pid=5400) 2820184 entries sent to bpdbm
09/10/2013 19:33:31 - Info bpbkar (pid=5400) 2922071 entries sent to bpdbm
09/10/2013 19:46:26 - Info bpbkar (pid=5400) 3077143 entries sent to bpdbm
09/10/2013 19:49:40 - Info bpbkar (pid=5400) 3159935 entries sent to bpdbm
09/10/2013 19:54:31 - Info bpbkar (pid=5400) 3271543 entries sent to bpdbm
09/10/2013 20:02:14 - Info bpbkar (pid=5400) 3454291 entries sent to bpdbm
09/10/2013 20:11:29 - Info bpbkar (pid=5400) 3560922 entries sent to bpdbm
09/10/2013 20:16:53 - Info bpbkar (pid=5400) change journal NOT enabled for <T:\>
09/10/2013 20:26:49 - Info bpbkar (pid=5400) 3744704 entries sent to bpdbm
09/10/2013 20:37:57 - Info bpbkar (pid=5400) 3928304 entries sent to bpdbm
09/10/2013 20:44:27 - Info bpbkar (pid=5400) 4115249 entries sent to bpdbm
09/10/2013 20:53:11 - Info bpbkar (pid=5400) 4302694 entries sent to bpdbm
09/10/2013 21:00:33 - Info bpbkar (pid=5400) 4487078 entries sent to bpdbm
09/10/2013 21:05:59 - Info bpbkar (pid=5400) 4670246 entries sent to bpdbm
09/10/2013 21:13:41 - Info bpbkar (pid=5400) 4856598 entries sent to bpdbm
09/10/2013 21:16:28 - Info bpbkar (pid=5400) change journal NOT enabled for <U:\>
09/10/2013 21:27:41 - Info bpbkar (pid=5400) 5041481 entries sent to bpdbm
09/10/2013 21:37:37 - Info bpbkar (pid=5400) 5225050 entries sent to bpdbm
09/10/2013 21:46:27 - Info bpbkar (pid=5400) 5408807 entries sent to bpdbm
09/10/2013 21:52:20 - Info bpbkar (pid=5400) 5591570 entries sent to bpdbm
09/10/2013 21:59:06 - Info bpbkar (pid=5400) 5774318 entries sent to bpdbm
09/10/2013 22:13:00 - Info bpbkar (pid=5400) 5957082 entries sent to bpdbm
09/10/2013 22:20:09 - Info bpbkar (pid=5400) 6141445 entries sent to bpdbm
09/10/2013 22:31:46 - Info bpbkar (pid=5400) 6326421 entries sent to bpdbm
09/10/2013 22:32:57 - Info bpbkar (pid=5400) change journal NOT enabled for <V:\>
09/10/2013 22:48:05 - Info bpbkar (pid=5400) 6510308 entries sent to bpdbm
09/10/2013 22:59:59 - Info bpbkar (pid=5400) 6696295 entries sent to bpdbm
09/10/2013 23:09:30 - Info bpbkar (pid=5400) 6881168 entries sent to bpdbm
09/10/2013 23:21:46 - Info bpbkar (pid=5400) 7065039 entries sent to bpdbm
09/10/2013 23:27:58 - Info bpbkar (pid=5400) 7251966 entries sent to bpdbm
09/10/2013 23:37:49 - Info bpbkar (pid=5400) change journal NOT enabled for <W:\>
09/10/2013 23:45:27 - Info bpbkar (pid=5400) 7422859 entries sent to bpdbm
09/10/2013 23:47:56 - Info bpbkar (pid=5400) 7427859 entries sent to bpdbm
09/10/2013 23:52:04 - Info bpbkar (pid=5400) 7432859 entries sent to bpdbm
09/10/2013 23:53:30 - Info bpbkar (pid=5400) 7437859 entries sent to bpdbm
09/10/2013 23:55:46 - Info bpbkar (pid=5400) 7442859 entries sent to bpdbm
09/11/2013 00:01:52 - Info bpbkar (pid=5400) 7449018 entries sent to bpdbm
09/11/2013 00:04:33 - Info bpbkar (pid=5400) 7454018 entries sent to bpdbm
09/11/2013 00:05:41 - Info bpbkar (pid=5400) 7459018 entries sent to bpdbm
09/11/2013 00:35:17 - Info bpbkar (pid=5400) 7467065 entries sent to bpdbm
09/11/2013 00:36:30 - Info bpbkar (pid=5400) 7472065 entries sent to bpdbm
09/11/2013 00:37:33 - Info bpbkar (pid=5400) 7477065 entries sent to bpdbm
09/11/2013 00:38:07 - Info bpbkar (pid=5400) 7482065 entries sent to bpdbm
09/11/2013 00:50:30 - Info bpbkar (pid=5400) 7489719 entries sent to bpdbm
09/11/2013 00:52:35 - Info bpbkar (pid=5400) 7494719 entries sent to bpdbm
09/11/2013 00:55:53 - Info bpbkar (pid=5400) 7499719 entries sent to bpdbm
09/11/2013 00:57:43 - Info bpbkar (pid=5400) 7508543 entries sent to bpdbm
09/11/2013 00:58:51 - Info bpbkar (pid=5400) 7513543 entries sent to bpdbm
09/11/2013 01:06:12 - Info bpbkar (pid=5400) 7518543 entries sent to bpdbm
09/11/2013 01:17:04 - Info bpbkar (pid=5400) 7527829 entries sent to bpdbm
09/11/2013 01:38:43 - Info bpbkar (pid=5400) 7534217 entries sent to bpdbm
09/11/2013 01:52:43 - Info bpbkar (pid=5400) 7542415 entries sent to bpdbm
09/11/2013 01:58:42 - Info bpbkar (pid=5400) 7550769 entries sent to bpdbm
09/11/2013 02:36:59 - Info bpbkar (pid=5400) 7562433 entries sent to bpdbm
09/11/2013 02:40:29 - Info bpbkar (pid=5400) 7567433 entries sent to bpdbm
09/11/2013 02:50:59 - Info bpbkar (pid=5400) 7574250 entries sent to bpdbm
09/11/2013 02:52:41 - Info bpbkar (pid=5400) 7579250 entries sent to bpdbm
09/11/2013 03:33:31 - Info bpbkar (pid=5400) 7592366 entries sent to bpdbm
09/11/2013 03:36:47 - Info bpbkar (pid=5400) 7597366 entries sent to bpdbm
09/11/2013 04:21:37 - Info bpbkar (pid=5400) 7611123 entries sent to bpdbm
09/11/2013 04:50:16 - Info bpbkar (pid=5400) 7623701 entries sent to bpdbm
09/11/2013 04:50:56 - Info bpbkar (pid=5400) 7628701 entries sent to bpdbm
09/11/2013 04:51:20 - Info bpbkar (pid=5400) 7633701 entries sent to bpdbm
09/11/2013 04:52:23 - Info bpbkar (pid=5400) 7638701 entries sent to bpdbm
09/11/2013 04:56:16 - Info bpbkar (pid=5400) 7643701 entries sent to bpdbm
09/11/2013 07:34:02 - Info bpbkar (pid=5400) 7661895 entries sent to bpdbm
09/11/2013 07:36:15 - Info bpbkar (pid=5400) 7666895 entries sent to bpdbm
09/11/2013 07:39:17 - Info bpbkar (pid=5400) 7671895 entries sent to bpdbm
09/11/2013 07:40:12 - Info bpbkar (pid=5400) 7676895 entries sent to bpdbm
09/11/2013 07:41:09 - Info bpbkar (pid=5400) 7681895 entries sent to bpdbm
09/11/2013 07:42:48 - Info bpbkar (pid=5400) 7691405 entries sent to bpdbm
09/11/2013 07:43:47 - Info bpbkar (pid=5400) 7696405 entries sent to bpdbm
09/11/2013 07:45:16 - Info bpbkar (pid=5400) 7701405 entries sent to bpdbm
09/11/2013 07:48:26 - Info bpbkar (pid=5400) 7706405 entries sent to bpdbm
09/11/2013 07:52:28 - Info bpbkar (pid=5400) 7711405 entries sent to bpdbm
09/11/2013 07:53:39 - Info bpbkar (pid=5400) 7716405 entries sent to bpdbm
09/11/2013 07:54:39 - Info bpbkar (pid=5400) 7721405 entries sent to bpdbm
09/11/2013 07:56:01 - Info bpbkar (pid=5400) 7726405 entries sent to bpdbm
09/11/2013 07:56:37 - Info bpbkar (pid=5400) 7731405 entries sent to bpdbm
09/11/2013 07:58:13 - Info bpbkar (pid=5400) 7738612 entries sent to bpdbm
09/11/2013 07:59:10 - Info bpbkar (pid=5400) 7743612 entries sent to bpdbm
09/11/2013 08:00:33 - Info bpbkar (pid=5400) 7748612 entries sent to bpdbm
09/11/2013 08:01:56 - Info bpbkar (pid=5400) 7753612 entries sent to bpdbm
09/11/2013 08:02:21 - Info bpbkar (pid=5400) 7758612 entries sent to bpdbm
09/11/2013 08:04:15 - Info bpbkar (pid=5400) 7763612 entries sent to bpdbm
09/11/2013 08:13:51 - Info bpbkar (pid=5400) 7771928 entries sent to bpdbm
09/11/2013 08:14:33 - Info bpbkar (pid=5400) 7776928 entries sent to bpdbm
09/11/2013 08:15:31 - Info bpbkar (pid=5400) 7781928 entries sent to bpdbm
09/11/2013 08:16:26 - Info bpbkar (pid=5400) 7786928 entries sent to bpdbm
09/11/2013 08:17:07 - Info bpbkar (pid=5400) 7791928 entries sent to bpdbm
09/11/2013 08:17:36 - Info bpbkar (pid=5400) 7796928 entries sent to bpdbm
09/11/2013 08:18:31 - Info bpbkar (pid=5400) 7801928 entries sent to bpdbm
09/11/2013 08:19:00 - Info bpbkar (pid=5400) 7806928 entries sent to bpdbm
09/11/2013 08:20:05 - Info bpbkar (pid=5400) 7811928 entries sent to bpdbm
09/11/2013 08:21:03 - Info bpbkar (pid=5400) 7816928 entries sent to bpdbm
09/11/2013 08:22:32 - Info bpbkar (pid=5400) 7821928 entries sent to bpdbm
09/11/2013 08:22:53 - Info bpbkar (pid=5400) 7826928 entries sent to bpdbm
09/11/2013 08:24:04 - Info bpbkar (pid=5400) 7831928 entries sent to bpdbm
09/11/2013 08:25:45 - Info bpbkar (pid=5400) 7836928 entries sent to bpdbm
09/11/2013 08:28:15 - Info bpbkar (pid=5400) 7845760 entries sent to bpdbm
09/11/2013 08:30:55 - Info bpbkar (pid=5400) 7850760 entries sent to bpdbm
09/11/2013 08:33:33 - Info bpbkar (pid=5400) 7855760 entries sent to bpdbm
09/11/2013 08:34:45 - Info bpbkar (pid=5400) 7860760 entries sent to bpdbm
09/11/2013 08:35:38 - Info bpbkar (pid=5400) 7865760 entries sent to bpdbm
09/11/2013 08:37:12 - Info bpbkar (pid=5400) 7870760 entries sent to bpdbm
09/11/2013 08:38:11 - Info bpbkar (pid=5400) 7875760 entries sent to bpdbm
09/11/2013 08:38:55 - Info bpbkar (pid=5400) 7880760 entries sent to bpdbm
09/11/2013 08:40:22 - Info bpbkar (pid=5400) 7885760 entries sent to bpdbm
09/11/2013 08:40:58 - Info bpbkar (pid=5400) 7890760 entries sent to bpdbm
09/11/2013 08:43:16 - Info bpbkar (pid=5400) 7899509 entries sent to bpdbm
09/11/2013 08:44:51 - Info bpbkar (pid=5400) 7904509 entries sent to bpdbm
09/11/2013 08:47:47 - Info bpbkar (pid=5400) 7909509 entries sent to bpdbm
09/11/2013 08:50:32 - Info bpbkar (pid=5400) 7914509 entries sent to bpdbm
09/11/2013 08:52:53 - Info bpbkar (pid=5400) 7919509 entries sent to bpdbm
09/11/2013 08:53:30 - Info bpbkar (pid=5400) 7924509 entries sent to bpdbm
09/11/2013 08:54:00 - Info bpbkar (pid=5400) 7929509 entries sent to bpdbm
09/11/2013 08:54:59 - Info bpbkar (pid=5400) 7934509 entries sent to bpdbm
09/11/2013 08:55:45 - Info bpbkar (pid=5400) 7939509 entries sent to bpdbm
09/11/2013 08:56:37 - Info bpbkar (pid=5400) 7944509 entries sent to bpdbm
09/11/2013 08:57:56 - Info bpbkar (pid=5400) 7951882 entries sent to bpdbm
09/11/2013 08:58:37 - Info bpbkar (pid=5400) 7956882 entries sent to bpdbm
09/11/2013 08:59:38 - Info bpbkar (pid=5400) 7961882 entries sent to bpdbm
09/11/2013 09:01:00 - Info bpbkar (pid=5400) 7966882 entries sent to bpdbm
09/11/2013 09:02:04 - Info bpbkar (pid=5400) 7971882 entries sent to bpdbm
09/11/2013 09:05:23 - Info bpbkar (pid=5400) 7976882 entries sent to bpdbm
09/11/2013 09:07:07 - Info bpbkar (pid=5400) 7981882 entries sent to bpdbm
09/11/2013 09:08:24 - Info bpbkar (pid=5400) 7986882 entries sent to bpdbm
09/11/2013 09:10:02 - Info bpbkar (pid=5400) 7991882 entries sent to bpdbm
09/11/2013 09:11:17 - Info bpbkar (pid=5400) 7996882 entries sent to bpdbm
09/11/2013 09:13:15 - Info bpbkar (pid=5400) 8005314 entries sent to bpdbm
09/11/2013 09:14:42 - Info bpbkar (pid=5400) 8010314 entries sent to bpdbm
09/11/2013 09:14:55 - Info bpbkar (pid=5400) 8015314 entries sent to bpdbm
09/11/2013 09:15:37 - Info bpbkar (pid=5400) 8020314 entries sent to bpdbm
09/11/2013 09:16:17 - Info bpbkar (pid=5400) 8025314 entries sent to bpdbm
09/11/2013 09:17:06 - Info bpbkar (pid=5400) 8030314 entries sent to bpdbm
09/11/2013 09:17:54 - Info bpbkar (pid=5400) 8035314 entries sent to bpdbm
09/11/2013 09:18:58 - Info bpbkar (pid=5400) 8040314 entries sent to bpdbm
09/11/2013 09:21:07 - Info bpbkar (pid=5400) 8045314 entries sent to bpdbm
09/11/2013 09:23:14 - Info bpbkar (pid=5400) 8050314 entries sent to bpdbm
09/11/2013 09:26:13 - Info bpbkar (pid=5400) 8055314 entries sent to bpdbm
09/11/2013 09:27:50 - Info bpbkar (pid=5400) 8062055 entries sent to bpdbm
09/11/2013 09:28:38 - Info bpbkar (pid=5400) 8067055 entries sent to bpdbm
09/11/2013 09:29:50 - Info bpbkar (pid=5400) 8072055 entries sent to bpdbm
09/11/2013 09:31:07 - Info bpbkar (pid=5400) 8077055 entries sent to bpdbm
09/11/2013 09:32:12 - Info bpbkar (pid=5400) 8082055 entries sent to bpdbm
09/11/2013 09:32:55 - Info bpbkar (pid=5400) 8087055 entries sent to bpdbm
09/11/2013 09:33:42 - Info bpbkar (pid=5400) 8092055 entries sent to bpdbm
09/11/2013 09:34:20 - Info bpbkar (pid=5400) 8097055 entries sent to bpdbm
09/11/2013 09:35:59 - Info bpbkar (pid=5400) 8102055 entries sent to bpdbm
09/11/2013 09:37:35 - Info bpbkar (pid=5400) 8107055 entries sent to bpdbm
That says it has no estimate of kb, so i assume this is its first run?
The first accelerator run is just a normal "slow" backup
This creates the information and track log and should be the last time it ever runs that slow - the next full backup will be a "real" accelerator backup
A typical example i see is 1st run takes 24 hours, next run takes 47 minutes
Let this run and do its stuff and then enjoy the accelerator on the next run!
Hope this helps