8.6.2021 21:00:01 - begin Duplicate
8.6.2021 21:00:02 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:00:02 - requesting resource  @aaaaw
8.6.2021 21:00:02 - reserving resource @aaaaw
8.6.2021 21:00:02 - resource @aaaaw reserved
8.6.2021 21:00:02 - granted resource  O002L8
8.6.2021 21:00:02 - granted resource  IBM.ULT3580-HH8.000
8.6.2021 21:00:02 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:00:02 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 21:00:09 - Info bptm (pid=153017) start
8.6.2021 21:00:09 - started process bptm (pid=153017)
8.6.2021 21:00:09 - Info bptm (pid=153017) start backup
8.6.2021 21:00:10 - Info bpdm (pid=153020) started
8.6.2021 21:00:10 - started process bpdm (pid=153020)
8.6.2021 21:00:10 - Info bpdm (pid=153020) reading backup image
8.6.2021 21:00:10 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:00:10 - Info bptm (pid=153017) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 21:00:10 - started process bptm (pid=153017)
8.6.2021 21:00:10 - mounting O002L8
8.6.2021 21:00:10 - Info bpdm (pid=153020) requesting nbjm for media
8.6.2021 21:00:10 - Info bptm (pid=153017) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:00:11 - begin reading
8.6.2021 21:00:55 - Info bptm (pid=153017) media id O002L8 mounted on drive index 0, drivepath /dev/tape/by-path/pci-0000:5e:00.1-fc-0x5000e11171beb066-lun-0-nst, drivename IBM.ULT3580-HH8.000, copy 4
8.6.2021 21:00:55 - Info bptm (pid=153017) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:02:09 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:02:10 - end reading; read time: 0:01:59
8.6.2021 21:02:10 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:11 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:11 - begin reading
8.6.2021 21:02:12 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:02:13 - end reading; read time: 0:00:02
8.6.2021 21:02:13 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:14 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:14 - begin reading
8.6.2021 21:02:14 - Info bptm (pid=153017) waited for full buffer 1 times, delayed 1 times
8.6.2021 21:02:15 - end reading; read time: 0:00:01
8.6.2021 21:02:15 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:16 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:16 - begin reading
8.6.2021 21:02:16 - Info bptm (pid=153017) waited for full buffer 3 times, delayed 3 times
8.6.2021 21:02:17 - end reading; read time: 0:00:01
8.6.2021 21:02:17 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:18 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:18 - begin reading
8.6.2021 21:02:18 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:02:19 - end reading; read time: 0:00:01
8.6.2021 21:02:19 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:20 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:20 - begin reading
8.6.2021 21:02:20 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:02:21 - end reading; read time: 0:00:01
8.6.2021 21:02:21 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:22 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:22 - begin reading
8.6.2021 21:02:22 - Info bptm (pid=153017) waited for full buffer 2 times, delayed 5 times
8.6.2021 21:02:23 - end reading; read time: 0:00:01
8.6.2021 21:02:23 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:24 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:24 - begin reading
8.6.2021 21:02:24 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:02:26 - end reading; read time: 0:00:02
8.6.2021 21:02:26 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:27 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:27 - begin reading
8.6.2021 21:02:30 - Info bptm (pid=153017) waited for full buffer 88 times, delayed 97 times
8.6.2021 21:02:31 - end reading; read time: 0:00:04
8.6.2021 21:02:31 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:31 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:31 - begin reading
8.6.2021 21:02:32 - Info bptm (pid=153017) waited for full buffer 4 times, delayed 5 times
8.6.2021 21:02:33 - end reading; read time: 0:00:02
8.6.2021 21:02:33 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:34 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:34 - begin reading
8.6.2021 21:02:34 - Info bptm (pid=153017) waited for full buffer 2 times, delayed 4 times
8.6.2021 21:02:35 - end reading; read time: 0:00:01
8.6.2021 21:02:35 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:36 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:36 - begin reading
8.6.2021 21:02:36 - Info bptm (pid=153017) waited for full buffer 1 times, delayed 1 times
8.6.2021 21:02:37 - end reading; read time: 0:00:01
8.6.2021 21:02:37 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:38 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:38 - begin reading
8.6.2021 21:02:41 - Info bptm (pid=153017) waited for full buffer 76 times, delayed 83 times
8.6.2021 21:02:42 - end reading; read time: 0:00:04
8.6.2021 21:02:42 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:43 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:43 - begin reading
8.6.2021 21:02:43 - Info bptm (pid=153017) waited for full buffer 6 times, delayed 8 times
8.6.2021 21:02:49 - end reading; read time: 0:00:06
8.6.2021 21:02:49 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:50 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:50 - begin reading
8.6.2021 21:02:54 - Info bptm (pid=153017) waited for full buffer 151 times, delayed 170 times
8.6.2021 21:02:55 - end reading; read time: 0:00:05
8.6.2021 21:02:56 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:56 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:56 - begin reading
8.6.2021 21:02:56 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:02:57 - end reading; read time: 0:00:01
8.6.2021 21:02:58 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:02:58 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:02:58 - begin reading
8.6.2021 21:02:59 - Info bptm (pid=153017) waited for full buffer 3 times, delayed 4 times
8.6.2021 21:03:03 - end reading; read time: 0:00:05
8.6.2021 21:03:03 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:03:03 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:03:03 - begin reading
8.6.2021 21:03:08 - Info bptm (pid=153017) waited for full buffer 162 times, delayed 178 times
8.6.2021 21:03:09 - end reading; read time: 0:00:06
8.6.2021 21:03:09 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:03:10 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:03:10 - begin reading
8.6.2021 21:03:10 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:03:11 - end reading; read time: 0:00:01
8.6.2021 21:03:11 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:03:12 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:03:12 - begin reading
8.6.2021 21:03:12 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:03:13 - end reading; read time: 0:00:01
8.6.2021 21:03:13 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:03:14 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:03:14 - begin reading
8.6.2021 21:03:14 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:03:18 - end reading; read time: 0:00:04
8.6.2021 21:03:18 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:03:19 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:03:19 - begin reading
8.6.2021 21:03:32 - Info bptm (pid=153017) waited for full buffer 456 times, delayed 501 times
8.6.2021 21:03:38 - end reading; read time: 0:00:19
8.6.2021 21:03:38 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:03:39 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:03:39 - begin reading
8.6.2021 21:03:56 - Info bptm (pid=153017) waited for full buffer 615 times, delayed 688 times
8.6.2021 21:03:59 - end reading; read time: 0:00:20
8.6.2021 21:04:00 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:04:00 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:04:00 - begin reading
8.6.2021 21:04:05 - Info bptm (pid=153017) waited for full buffer 134 times, delayed 144 times
8.6.2021 21:04:06 - end reading; read time: 0:00:06
8.6.2021 21:04:06 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:04:06 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:04:07 - begin reading
8.6.2021 21:04:11 - Info bptm (pid=153017) waited for full buffer 117 times, delayed 126 times
8.6.2021 21:04:12 - end reading; read time: 0:00:05
8.6.2021 21:04:12 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:04:13 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:04:13 - begin reading
8.6.2021 21:04:19 - Info bptm (pid=153017) waited for full buffer 143 times, delayed 150 times
8.6.2021 21:04:24 - end reading; read time: 0:00:11
8.6.2021 21:04:24 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:04:25 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:04:25 - begin reading
8.6.2021 21:04:31 - Info bptm (pid=153017) waited for full buffer 153 times, delayed 161 times
8.6.2021 21:04:36 - end reading; read time: 0:00:11
8.6.2021 21:04:36 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:04:37 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:04:37 - begin reading
8.6.2021 21:04:59 - Info bptm (pid=153017) waited for full buffer 705 times, delayed 750 times
8.6.2021 21:05:05 - end reading; read time: 0:00:28
8.6.2021 21:05:05 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:05:06 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:05:06 - begin reading
8.6.2021 21:05:27 - Info bptm (pid=153017) waited for full buffer 657 times, delayed 680 times
8.6.2021 21:05:30 - end reading; read time: 0:00:24
8.6.2021 21:05:30 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:05:31 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:05:31 - begin reading
8.6.2021 21:05:46 - Info bptm (pid=153017) waited for full buffer 487 times, delayed 528 times
8.6.2021 21:05:49 - end reading; read time: 0:00:18
8.6.2021 21:05:50 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:05:50 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:05:50 - begin reading
8.6.2021 21:06:17 - Info bptm (pid=153017) waited for full buffer 577 times, delayed 740 times
8.6.2021 21:06:23 - end reading; read time: 0:00:33
8.6.2021 21:06:23 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:06:23 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:06:24 - begin reading
8.6.2021 21:06:44 - Info bptm (pid=153017) waited for full buffer 488 times, delayed 640 times
8.6.2021 21:06:46 - end reading; read time: 0:00:22
8.6.2021 21:06:47 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:06:47 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:06:47 - begin reading
8.6.2021 21:07:38 - Info bptm (pid=153017) waited for full buffer 552 times, delayed 2622 times
8.6.2021 21:07:44 - end reading; read time: 0:00:57
8.6.2021 21:07:44 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:07:45 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:07:45 - begin reading
8.6.2021 21:07:59 - Info bptm (pid=153017) waited for full buffer 306 times, delayed 355 times
8.6.2021 21:08:05 - end reading; read time: 0:00:20
8.6.2021 21:08:05 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:08:06 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:08:06 - begin reading
8.6.2021 21:08:32 - Info bptm (pid=153017) waited for full buffer 444 times, delayed 516 times
8.6.2021 21:08:33 - end reading; read time: 0:00:27
8.6.2021 21:08:33 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:08:34 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:08:34 - begin reading
8.6.2021 21:08:34 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:08:36 - end reading; read time: 0:00:02
8.6.2021 21:08:36 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:08:36 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:08:37 - begin reading
8.6.2021 21:08:42 - Info bptm (pid=153017) waited for full buffer 156 times, delayed 186 times
8.6.2021 21:08:43 - end reading; read time: 0:00:06
8.6.2021 21:08:44 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:08:44 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:08:44 - begin reading
8.6.2021 21:08:44 - Info bptm (pid=153017) waited for full buffer 3 times, delayed 5 times
8.6.2021 21:08:47 - end reading; read time: 0:00:03
8.6.2021 21:08:48 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:08:48 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:08:48 - begin reading
8.6.2021 21:09:02 - Info bptm (pid=153017) waited for full buffer 305 times, delayed 347 times
8.6.2021 21:09:08 - end reading; read time: 0:00:20
8.6.2021 21:09:08 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:09:09 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:09:09 - begin reading
8.6.2021 21:09:24 - Info bptm (pid=153017) waited for full buffer 364 times, delayed 422 times
8.6.2021 21:09:26 - end reading; read time: 0:00:17
8.6.2021 21:09:26 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:09:27 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:09:27 - begin reading
8.6.2021 21:09:32 - Info bptm (pid=153017) waited for full buffer 112 times, delayed 123 times
8.6.2021 21:09:34 - end reading; read time: 0:00:07
8.6.2021 21:09:34 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:09:35 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:09:35 - begin reading
8.6.2021 21:09:35 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:09:36 - end reading; read time: 0:00:01
8.6.2021 21:09:36 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:09:37 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:09:37 - begin reading
8.6.2021 21:09:37 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:09:38 - end reading; read time: 0:00:01
8.6.2021 21:09:38 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:09:39 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:09:39 - begin reading
8.6.2021 21:09:39 - Info bptm (pid=153017) waited for full buffer 2 times, delayed 2 times
8.6.2021 21:09:43 - end reading; read time: 0:00:04
8.6.2021 21:09:43 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:09:44 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:09:44 - begin reading
8.6.2021 21:09:50 - Info bptm (pid=153017) waited for full buffer 177 times, delayed 209 times
8.6.2021 21:09:54 - end reading; read time: 0:00:10
8.6.2021 21:09:54 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:09:55 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:09:55 - begin reading
8.6.2021 21:09:55 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:09:56 - end reading; read time: 0:00:01
8.6.2021 21:09:56 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:09:57 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:09:57 - begin reading
8.6.2021 21:09:57 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:09:58 - end reading; read time: 0:00:01
8.6.2021 21:09:58 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:09:59 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:09:59 - begin reading
8.6.2021 21:09:59 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:10:01 - end reading; read time: 0:00:02
8.6.2021 21:10:01 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:10:02 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:10:02 - begin reading
8.6.2021 21:10:16 - Info bptm (pid=153017) waited for full buffer 301 times, delayed 343 times
8.6.2021 21:10:22 - end reading; read time: 0:00:20
8.6.2021 21:10:22 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:10:23 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:10:23 - begin reading
8.6.2021 21:10:23 - Info bptm (pid=153017) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:10:24 - end reading; read time: 0:00:01
8.6.2021 21:10:24 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:10:25 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:10:25 - begin reading
8.6.2021 21:10:25 - Info bptm (pid=153017) waited for full buffer 2 times, delayed 2 times
8.6.2021 21:10:27 - end reading; read time: 0:00:02
8.6.2021 21:10:27 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:10:28 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:10:28 - begin reading
8.6.2021 21:10:45 - Info bptm (pid=153017) waited for full buffer 462 times, delayed 601 times
8.6.2021 21:10:55 - end reading; read time: 0:00:27
8.6.2021 21:10:55 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:10:56 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:10:56 - begin reading
8.6.2021 21:10:59 - Info bptm (pid=153017) waited for full buffer 80 times, delayed 132 times
8.6.2021 21:11:00 - end reading; read time: 0:00:04
8.6.2021 21:11:00 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:11:01 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:11:01 - begin reading
8.6.2021 21:11:09 - Info bptm (pid=153017) waited for full buffer 209 times, delayed 293 times
8.6.2021 21:11:14 - end reading; read time: 0:00:13
8.6.2021 21:11:14 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:11:15 - Info bpdm (pid=153020) using 256 data buffers
8.6.2021 21:11:15 - begin reading
8.6.2021 21:11:19 - Info bptm (pid=153017) waited for full buffer 103 times, delayed 147 times
8.6.2021 21:11:26 - Info bptm (pid=153017) EXITING with status 0 <----------
8.6.2021 21:11:26 - end reading; read time: 0:00:11
8.6.2021 21:11:26 - Info bpdm (pid=153020) completed reading backup image
8.6.2021 21:11:26 - Info bpdm (pid=153020) EXITING with status 0
8.6.2021 21:11:26 - Info vdc3bck1.mycompany.com (pid=153020) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 100180484 KB, CR received: 31839740 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 21:11:26 - requesting resource  @aaaaw
8.6.2021 21:11:26 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 21:11:28 - Info bptm (pid=154186) start
8.6.2021 21:11:28 - started process bptm (pid=154186)
8.6.2021 21:11:28 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:11:28 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 21:12:29 - granted resource  O002L8
8.6.2021 21:12:29 - granted resource  IBM.ULT3580-HH8.001
8.6.2021 21:12:29 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:12:30 - Info bptm (pid=154186) start backup
8.6.2021 21:12:30 - Info bpdm (pid=154261) started
8.6.2021 21:12:30 - started process bpdm (pid=154261)
8.6.2021 21:12:30 - Info bpdm (pid=154261) reading backup image
8.6.2021 21:12:30 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:12:30 - Info bptm (pid=154186) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 21:12:30 - started process bptm (pid=154186)
8.6.2021 21:12:30 - mounting O002L8
8.6.2021 21:12:30 - Info bpdm (pid=154261) requesting nbjm for media
8.6.2021 21:12:30 - Info bptm (pid=154186) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:12:31 - begin reading
8.6.2021 21:13:10 - Info bptm (pid=154186) media id O002L8 mounted on drive index 1, drivepath /dev/tape/by-path/pci-0000:5e:00.0-fc-0x5000e11171beb070-lun-0-nst, drivename IBM.ULT3580-HH8.001, copy 4
8.6.2021 21:13:10 - Info bptm (pid=154186) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:13:40 - Info bptm (pid=154186) waited for full buffer 60 times, delayed 60 times
8.6.2021 21:13:44 - end reading; read time: 0:01:13
8.6.2021 21:13:44 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:13:45 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:13:45 - begin reading
8.6.2021 21:13:47 - Info bptm (pid=154186) waited for full buffer 42 times, delayed 42 times
8.6.2021 21:13:49 - end reading; read time: 0:00:04
8.6.2021 21:13:50 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:13:50 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:13:50 - begin reading
8.6.2021 21:14:20 - Info bptm (pid=154186) waited for full buffer 323 times, delayed 1807 times
8.6.2021 21:14:26 - end reading; read time: 0:00:36
8.6.2021 21:14:27 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:14:27 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:14:27 - begin reading
8.6.2021 21:14:35 - Info bptm (pid=154186) waited for full buffer 220 times, delayed 296 times
8.6.2021 21:14:40 - end reading; read time: 0:00:13
8.6.2021 21:14:40 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:14:41 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:14:41 - begin reading
8.6.2021 21:14:41 - Info bptm (pid=154186) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:14:43 - end reading; read time: 0:00:02
8.6.2021 21:14:43 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:14:44 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:14:44 - begin reading
8.6.2021 21:15:00 - Info bptm (pid=154186) waited for full buffer 461 times, delayed 575 times
8.6.2021 21:15:04 - end reading; read time: 0:00:20
8.6.2021 21:15:04 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:15:05 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:15:05 - begin reading
8.6.2021 21:15:05 - Info bptm (pid=154186) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:15:08 - end reading; read time: 0:00:03
8.6.2021 21:15:08 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:15:09 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:15:09 - begin reading
8.6.2021 21:15:31 - Info bptm (pid=154186) waited for full buffer 336 times, delayed 435 times
8.6.2021 21:15:35 - end reading; read time: 0:00:26
8.6.2021 21:15:35 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:15:36 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:15:36 - begin reading
8.6.2021 21:15:40 - Info bptm (pid=154186) waited for full buffer 132 times, delayed 137 times
8.6.2021 21:15:41 - end reading; read time: 0:00:05
8.6.2021 21:15:42 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:15:42 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:15:42 - begin reading
8.6.2021 21:15:42 - Info bptm (pid=154186) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:15:45 - end reading; read time: 0:00:03
8.6.2021 21:15:46 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:15:46 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:15:46 - begin reading
8.6.2021 21:15:50 - Info bptm (pid=154186) waited for full buffer 72 times, delayed 73 times
8.6.2021 21:15:52 - end reading; read time: 0:00:06
8.6.2021 21:15:52 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:15:53 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:15:53 - begin reading
8.6.2021 21:16:37 - Info bptm (pid=154186) waited for full buffer 781 times, delayed 1044 times
8.6.2021 21:16:41 - end reading; read time: 0:00:48
8.6.2021 21:16:41 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:16:42 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:16:42 - begin reading
8.6.2021 21:16:42 - Info bptm (pid=154186) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:16:44 - end reading; read time: 0:00:02
8.6.2021 21:16:44 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:16:45 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:16:45 - begin reading
8.6.2021 21:16:52 - Info bptm (pid=154186) waited for full buffer 172 times, delayed 180 times
8.6.2021 21:16:58 - end reading; read time: 0:00:13
8.6.2021 21:16:59 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:16:59 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:16:59 - begin reading
8.6.2021 21:17:14 - Info bptm (pid=154186) waited for full buffer 408 times, delayed 457 times
8.6.2021 21:17:21 - end reading; read time: 0:00:22
8.6.2021 21:17:21 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:17:22 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:17:22 - begin reading
8.6.2021 21:17:25 - Info bptm (pid=154186) waited for full buffer 66 times, delayed 66 times
8.6.2021 21:17:27 - end reading; read time: 0:00:05
8.6.2021 21:17:27 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:17:28 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:17:28 - begin reading
8.6.2021 21:17:35 - Info bptm (pid=154186) waited for full buffer 137 times, delayed 137 times
8.6.2021 21:17:39 - end reading; read time: 0:00:11
8.6.2021 21:17:39 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:17:40 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:17:40 - begin reading
8.6.2021 21:18:17 - Info bptm (pid=154186) waited for full buffer 923 times, delayed 1144 times
8.6.2021 21:18:23 - end reading; read time: 0:00:43
8.6.2021 21:18:23 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:18:24 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:18:24 - begin reading
8.6.2021 21:18:45 - Info bptm (pid=154186) waited for full buffer 480 times, delayed 570 times
8.6.2021 21:18:51 - end reading; read time: 0:00:27
8.6.2021 21:18:51 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:18:52 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:18:52 - begin reading
8.6.2021 21:18:58 - Info bptm (pid=154186) waited for full buffer 110 times, delayed 165 times
8.6.2021 21:19:02 - end reading; read time: 0:00:10
8.6.2021 21:19:02 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:19:03 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:19:03 - begin reading
8.6.2021 21:19:06 - Info bptm (pid=154186) waited for full buffer 60 times, delayed 69 times
8.6.2021 21:19:07 - end reading; read time: 0:00:04
8.6.2021 21:19:07 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:19:07 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:19:08 - begin reading
8.6.2021 21:19:10 - Info bptm (pid=154186) waited for full buffer 60 times, delayed 73 times
8.6.2021 21:19:17 - end reading; read time: 0:00:09
8.6.2021 21:19:17 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:19:18 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:19:18 - begin reading
8.6.2021 21:19:22 - Info bptm (pid=154186) waited for full buffer 47 times, delayed 47 times
8.6.2021 21:19:23 - end reading; read time: 0:00:05
8.6.2021 21:19:23 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:19:24 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:19:24 - begin reading
8.6.2021 21:19:49 - Info bptm (pid=154186) waited for full buffer 603 times, delayed 825 times
8.6.2021 21:19:52 - end reading; read time: 0:00:28
8.6.2021 21:19:52 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:19:53 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:19:53 - begin reading
8.6.2021 21:20:08 - Info bptm (pid=154186) waited for full buffer 215 times, delayed 215 times
8.6.2021 21:20:12 - end reading; read time: 0:00:19
8.6.2021 21:20:13 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:20:13 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:20:13 - begin reading
8.6.2021 21:20:38 - Info bptm (pid=154186) waited for full buffer 439 times, delayed 500 times
8.6.2021 21:20:42 - end reading; read time: 0:00:29
8.6.2021 21:20:42 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:20:43 - Info bpdm (pid=154261) using 256 data buffers
8.6.2021 21:20:43 - begin reading
8.6.2021 21:21:27 - Info bptm (pid=154186) waited for full buffer 1173 times, delayed 1509 times
8.6.2021 21:21:34 - Info bptm (pid=154186) EXITING with status 0 <----------
8.6.2021 21:21:34 - end reading; read time: 0:00:51
8.6.2021 21:21:34 - Info bpdm (pid=154261) completed reading backup image
8.6.2021 21:21:34 - Info bpdm (pid=154261) EXITING with status 0
8.6.2021 21:21:34 - Info vdc3bck1.mycompany.com (pid=154261) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 102274417 KB, CR received: 31258671 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 21:21:34 - requesting resource  @aaaaw
8.6.2021 21:21:34 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 21:21:36 - Info bptm (pid=155092) start
8.6.2021 21:21:36 - started process bptm (pid=155092)
8.6.2021 21:21:36 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:21:36 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 21:23:31 - granted resource  O002L8
8.6.2021 21:23:31 - granted resource  IBM.ULT3580-HH8.002
8.6.2021 21:23:31 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:23:31 - Info bptm (pid=155092) start backup
8.6.2021 21:23:32 - Info bpdm (pid=155211) started
8.6.2021 21:23:32 - started process bpdm (pid=155211)
8.6.2021 21:23:32 - Info bpdm (pid=155211) reading backup image
8.6.2021 21:23:32 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:23:32 - Info bptm (pid=155092) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 21:23:32 - started process bptm (pid=155092)
8.6.2021 21:23:32 - mounting O002L8
8.6.2021 21:23:32 - Info bpdm (pid=155211) requesting nbjm for media
8.6.2021 21:23:32 - Info bptm (pid=155092) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:23:33 - begin reading
8.6.2021 21:24:13 - Info bptm (pid=155092) media id O002L8 mounted on drive index 2, drivepath /dev/tape/by-path/pci-0000:5e:00.0-fc-0x5000e11171beb05c-lun-0-nst, drivename IBM.ULT3580-HH8.002, copy 4
8.6.2021 21:24:14 - Info bptm (pid=155092) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:25:46 - Info bptm (pid=155092) waited for full buffer 606 times, delayed 676 times
8.6.2021 21:25:48 - end reading; read time: 0:02:15
8.6.2021 21:25:48 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:25:49 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:25:49 - begin reading
8.6.2021 21:25:49 - Info bptm (pid=155092) waited for full buffer 4 times, delayed 6 times
8.6.2021 21:25:51 - end reading; read time: 0:00:02
8.6.2021 21:25:51 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:25:52 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:25:52 - begin reading
8.6.2021 21:25:58 - Info bptm (pid=155092) waited for full buffer 173 times, delayed 243 times
8.6.2021 21:26:02 - end reading; read time: 0:00:10
8.6.2021 21:26:02 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:26:03 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:26:03 - begin reading
8.6.2021 21:26:30 - Info bptm (pid=155092) waited for full buffer 669 times, delayed 858 times
8.6.2021 21:26:37 - end reading; read time: 0:00:34
8.6.2021 21:26:37 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:26:38 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:26:38 - begin reading
8.6.2021 21:27:16 - Info bptm (pid=155092) waited for full buffer 945 times, delayed 1239 times
8.6.2021 21:27:19 - end reading; read time: 0:00:41
8.6.2021 21:27:20 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:27:20 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:27:20 - begin reading
8.6.2021 21:27:37 - Info bptm (pid=155092) waited for full buffer 285 times, delayed 286 times
8.6.2021 21:27:42 - end reading; read time: 0:00:22
8.6.2021 21:27:42 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:27:43 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:27:43 - begin reading
8.6.2021 21:28:09 - Info bptm (pid=155092) waited for full buffer 684 times, delayed 904 times
8.6.2021 21:28:13 - end reading; read time: 0:00:30
8.6.2021 21:28:13 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:28:14 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:28:14 - begin reading
8.6.2021 21:28:31 - Info bptm (pid=155092) waited for full buffer 260 times, delayed 260 times
8.6.2021 21:28:36 - end reading; read time: 0:00:22
8.6.2021 21:28:36 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:28:37 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:28:37 - begin reading
8.6.2021 21:28:37 - Info bptm (pid=155092) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:28:40 - end reading; read time: 0:00:03
8.6.2021 21:28:40 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:28:41 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:28:41 - begin reading
8.6.2021 21:28:46 - Info bptm (pid=155092) waited for full buffer 143 times, delayed 208 times
8.6.2021 21:28:50 - end reading; read time: 0:00:09
8.6.2021 21:28:50 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:28:51 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:28:51 - begin reading
8.6.2021 21:29:08 - Info bptm (pid=155092) waited for full buffer 429 times, delayed 545 times
8.6.2021 21:29:10 - end reading; read time: 0:00:19
8.6.2021 21:29:10 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:29:11 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:29:11 - begin reading
8.6.2021 21:29:53 - Info bptm (pid=155092) waited for full buffer 1085 times, delayed 1369 times
8.6.2021 21:29:58 - end reading; read time: 0:00:47
8.6.2021 21:29:58 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:29:59 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:29:59 - begin reading
8.6.2021 21:30:28 - Info bptm (pid=155092) waited for full buffer 821 times, delayed 1102 times
8.6.2021 21:30:31 - end reading; read time: 0:00:32
8.6.2021 21:30:31 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:30:32 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:30:32 - begin reading
8.6.2021 21:30:49 - Info bptm (pid=155092) waited for full buffer 470 times, delayed 621 times
8.6.2021 21:30:52 - end reading; read time: 0:00:20
8.6.2021 21:30:52 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:30:53 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:30:53 - begin reading
8.6.2021 21:31:11 - Info bptm (pid=155092) waited for full buffer 235 times, delayed 236 times
8.6.2021 21:31:15 - end reading; read time: 0:00:22
8.6.2021 21:31:16 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:31:16 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:31:16 - begin reading
8.6.2021 21:31:22 - Info bptm (pid=155092) waited for full buffer 123 times, delayed 150 times
8.6.2021 21:31:28 - end reading; read time: 0:00:12
8.6.2021 21:31:29 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:31:29 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:31:29 - begin reading
8.6.2021 21:31:29 - Info bptm (pid=155092) waited for full buffer 0 times, delayed 0 times
8.6.2021 21:31:31 - end reading; read time: 0:00:02
8.6.2021 21:31:31 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:31:32 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:31:32 - begin reading
8.6.2021 21:32:16 - Info bptm (pid=155092) waited for full buffer 1192 times, delayed 1609 times
8.6.2021 21:32:19 - end reading; read time: 0:00:47
8.6.2021 21:32:19 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:32:20 - Info bpdm (pid=155211) using 256 data buffers
8.6.2021 21:32:20 - begin reading
8.6.2021 21:32:46 - Info bptm (pid=155092) waited for full buffer 648 times, delayed 939 times
8.6.2021 21:32:53 - Info bptm (pid=155092) EXITING with status 0 <----------
8.6.2021 21:32:53 - end reading; read time: 0:00:33
8.6.2021 21:32:54 - Info bpdm (pid=155211) completed reading backup image
8.6.2021 21:32:54 - Info bpdm (pid=155211) EXITING with status 0
8.6.2021 21:32:54 - Info vdc3bck1.mycompany.com (pid=155211) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 106067052 KB, CR received: 35333438 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 21:32:54 - requesting resource  @aaaaw
8.6.2021 21:32:54 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 21:32:55 - Info bptm (pid=156162) start
8.6.2021 21:32:55 - started process bptm (pid=156162)
8.6.2021 21:32:55 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:32:56 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 21:35:22 - granted resource  O002L8
8.6.2021 21:35:22 - granted resource  IBM.ULT3580-HH8.000
8.6.2021 21:35:22 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:35:23 - Info bptm (pid=156162) start backup
8.6.2021 21:35:23 - Info bpdm (pid=156400) started
8.6.2021 21:35:23 - started process bpdm (pid=156400)
8.6.2021 21:35:23 - Info bpdm (pid=156400) reading backup image
8.6.2021 21:35:24 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:35:24 - Info bptm (pid=156162) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 21:35:24 - started process bptm (pid=156162)
8.6.2021 21:35:24 - mounting O002L8
8.6.2021 21:35:24 - Info bpdm (pid=156400) requesting nbjm for media
8.6.2021 21:35:24 - Info bptm (pid=156162) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:35:24 - begin reading
8.6.2021 21:36:04 - Info bptm (pid=156162) media id O002L8 mounted on drive index 0, drivepath /dev/tape/by-path/pci-0000:5e:00.1-fc-0x5000e11171beb066-lun-0-nst, drivename IBM.ULT3580-HH8.000, copy 4
8.6.2021 21:36:04 - Info bptm (pid=156162) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:38:59 - Info bptm (pid=156162) waited for full buffer 736 times, delayed 749 times
8.6.2021 21:39:02 - end reading; read time: 0:03:38
8.6.2021 21:39:02 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:39:03 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:39:03 - begin reading
8.6.2021 21:39:23 - Info bptm (pid=156162) waited for full buffer 471 times, delayed 682 times
8.6.2021 21:39:24 - end reading; read time: 0:00:21
8.6.2021 21:39:24 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:39:25 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:39:25 - begin reading
8.6.2021 21:39:35 - Info bptm (pid=156162) waited for full buffer 36 times, delayed 37 times
8.6.2021 21:39:40 - end reading; read time: 0:00:15
8.6.2021 21:39:40 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:39:41 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:39:41 - begin reading
8.6.2021 21:39:46 - Info bptm (pid=156162) waited for full buffer 117 times, delayed 184 times
8.6.2021 21:39:47 - end reading; read time: 0:00:06
8.6.2021 21:39:47 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:39:48 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:39:48 - begin reading
8.6.2021 21:40:31 - Info bptm (pid=156162) waited for full buffer 806 times, delayed 1275 times
8.6.2021 21:40:37 - end reading; read time: 0:00:49
8.6.2021 21:40:37 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:40:38 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:40:38 - begin reading
8.6.2021 21:40:59 - Info bptm (pid=156162) waited for full buffer 545 times, delayed 751 times
8.6.2021 21:41:00 - end reading; read time: 0:00:22
8.6.2021 21:41:01 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:41:01 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:41:02 - begin reading
8.6.2021 21:41:15 - Info bptm (pid=156162) waited for full buffer 289 times, delayed 439 times
8.6.2021 21:41:18 - end reading; read time: 0:00:16
8.6.2021 21:41:18 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:41:19 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:41:19 - begin reading
8.6.2021 21:41:42 - Info bptm (pid=156162) waited for full buffer 594 times, delayed 986 times
8.6.2021 21:41:48 - end reading; read time: 0:00:29
8.6.2021 21:41:48 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:41:49 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:41:49 - begin reading
8.6.2021 21:42:05 - Info bptm (pid=156162) waited for full buffer 311 times, delayed 459 times
8.6.2021 21:42:08 - end reading; read time: 0:00:19
8.6.2021 21:42:08 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:42:09 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:42:09 - begin reading
8.6.2021 21:42:19 - Info bptm (pid=156162) waited for full buffer 23 times, delayed 23 times
8.6.2021 21:42:24 - end reading; read time: 0:00:15
8.6.2021 21:42:24 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:42:25 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:42:25 - begin reading
8.6.2021 21:42:44 - Info bptm (pid=156162) waited for full buffer 291 times, delayed 454 times
8.6.2021 21:42:49 - end reading; read time: 0:00:24
8.6.2021 21:42:49 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:42:50 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:42:50 - begin reading
8.6.2021 21:43:00 - Info bptm (pid=156162) waited for full buffer 35 times, delayed 35 times
8.6.2021 21:43:05 - end reading; read time: 0:00:15
8.6.2021 21:43:06 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:43:06 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:43:06 - begin reading
8.6.2021 21:43:24 - Info bptm (pid=156162) waited for full buffer 310 times, delayed 439 times
8.6.2021 21:43:28 - end reading; read time: 0:00:22
8.6.2021 21:43:28 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:43:29 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:43:29 - begin reading
8.6.2021 21:44:03 - Info bptm (pid=156162) waited for full buffer 640 times, delayed 909 times
8.6.2021 21:44:10 - end reading; read time: 0:00:41
8.6.2021 21:44:10 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:44:11 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:44:11 - begin reading
8.6.2021 21:44:34 - Info bptm (pid=156162) waited for full buffer 469 times, delayed 747 times
8.6.2021 21:44:38 - end reading; read time: 0:00:27
8.6.2021 21:44:39 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:44:39 - Info bpdm (pid=156400) using 256 data buffers
8.6.2021 21:44:39 - begin reading
8.6.2021 21:45:41 - Info bptm (pid=156162) waited for full buffer 1454 times, delayed 2530 times
8.6.2021 21:45:52 - Info bptm (pid=156162) EXITING with status 0 <----------
8.6.2021 21:45:53 - end reading; read time: 0:01:14
8.6.2021 21:45:53 - Info bpdm (pid=156400) completed reading backup image
8.6.2021 21:45:53 - Info bpdm (pid=156400) EXITING with status 0
8.6.2021 21:45:53 - Info vdc3bck1.mycompany.com (pid=156400) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 106914314 KB, CR received: 35189585 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 21:45:53 - requesting resource  @aaaaw
8.6.2021 21:45:53 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 21:45:55 - Info bptm (pid=157550) start
8.6.2021 21:45:55 - started process bptm (pid=157550)
8.6.2021 21:45:55 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:45:55 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 21:47:45 - granted resource  O002L8
8.6.2021 21:47:45 - granted resource  IBM.ULT3580-HH8.001
8.6.2021 21:47:45 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:47:45 - Info bptm (pid=157550) start backup
8.6.2021 21:47:46 - Info bpdm (pid=157674) started
8.6.2021 21:47:46 - started process bpdm (pid=157674)
8.6.2021 21:47:46 - Info bpdm (pid=157674) reading backup image
8.6.2021 21:47:46 - Info bpdm (pid=157674) using 256 data buffers
8.6.2021 21:47:46 - Info bptm (pid=157550) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 21:47:46 - started process bptm (pid=157550)
8.6.2021 21:47:46 - mounting O002L8
8.6.2021 21:47:46 - Info bpdm (pid=157674) requesting nbjm for media
8.6.2021 21:47:46 - Info bptm (pid=157550) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:47:47 - begin reading
8.6.2021 21:48:28 - Info bptm (pid=157550) media id O002L8 mounted on drive index 1, drivepath /dev/tape/by-path/pci-0000:5e:00.0-fc-0x5000e11171beb070-lun-0-nst, drivename IBM.ULT3580-HH8.001, copy 4
8.6.2021 21:48:28 - Info bptm (pid=157550) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:49:52 - Info bptm (pid=157550) waited for full buffer 1026 times, delayed 1228 times
8.6.2021 21:49:55 - end reading; read time: 0:02:08
8.6.2021 21:49:55 - Info bpdm (pid=157674) completed reading backup image
8.6.2021 21:49:56 - Info bpdm (pid=157674) using 256 data buffers
8.6.2021 21:49:56 - begin reading
8.6.2021 21:50:24 - Info bptm (pid=157550) waited for full buffer 710 times, delayed 991 times
8.6.2021 21:50:27 - end reading; read time: 0:00:31
8.6.2021 21:50:28 - Info bpdm (pid=157674) completed reading backup image
8.6.2021 21:50:28 - Info bpdm (pid=157674) using 256 data buffers
8.6.2021 21:50:29 - begin reading
8.6.2021 21:51:24 - Info bptm (pid=157550) waited for full buffer 1100 times, delayed 1582 times
8.6.2021 21:51:29 - end reading; read time: 0:01:00
8.6.2021 21:51:29 - Info bpdm (pid=157674) completed reading backup image
8.6.2021 21:51:30 - Info bpdm (pid=157674) using 256 data buffers
8.6.2021 21:51:30 - begin reading
8.6.2021 21:52:06 - Info bptm (pid=157550) waited for full buffer 602 times, delayed 880 times
8.6.2021 21:52:09 - end reading; read time: 0:00:39
8.6.2021 21:52:09 - Info bpdm (pid=157674) completed reading backup image
8.6.2021 21:52:10 - Info bpdm (pid=157674) using 256 data buffers
8.6.2021 21:52:10 - begin reading
8.6.2021 21:52:29 - Info bptm (pid=157550) waited for full buffer 95 times, delayed 96 times
8.6.2021 21:52:33 - end reading; read time: 0:00:23
8.6.2021 21:52:33 - Info bpdm (pid=157674) completed reading backup image
8.6.2021 21:52:34 - Info bpdm (pid=157674) using 256 data buffers
8.6.2021 21:52:34 - begin reading
8.6.2021 21:53:14 - Info bptm (pid=157550) waited for full buffer 531 times, delayed 796 times
8.6.2021 21:53:17 - end reading; read time: 0:00:43
8.6.2021 21:53:17 - Info bpdm (pid=157674) completed reading backup image
8.6.2021 21:53:18 - Info bpdm (pid=157674) using 256 data buffers
8.6.2021 21:53:18 - begin reading
8.6.2021 21:53:42 - Info bptm (pid=157550) waited for full buffer 226 times, delayed 328 times
8.6.2021 21:53:46 - end reading; read time: 0:00:28
8.6.2021 21:53:46 - Info bpdm (pid=157674) completed reading backup image
8.6.2021 21:53:47 - Info bpdm (pid=157674) using 256 data buffers
8.6.2021 21:53:47 - begin reading
8.6.2021 21:54:05 - Info bptm (pid=157550) waited for full buffer 44 times, delayed 44 times
8.6.2021 21:54:09 - end reading; read time: 0:00:22
8.6.2021 21:54:09 - Info bpdm (pid=157674) completed reading backup image
8.6.2021 21:54:10 - Info bpdm (pid=157674) using 256 data buffers
8.6.2021 21:54:10 - begin reading
8.6.2021 21:54:43 - Info bptm (pid=157550) waited for full buffer 354 times, delayed 488 times
8.6.2021 21:54:51 - end reading; read time: 0:00:41
8.6.2021 21:54:51 - Info bpdm (pid=157674) completed reading backup image
8.6.2021 21:54:52 - Info bpdm (pid=157674) using 256 data buffers
8.6.2021 21:54:52 - begin reading
8.6.2021 21:55:16 - Info bptm (pid=157550) waited for full buffer 250 times, delayed 374 times
8.6.2021 21:55:30 - Info bptm (pid=157550) EXITING with status 0 <----------
8.6.2021 21:55:31 - end reading; read time: 0:00:39
8.6.2021 21:55:31 - Info bpdm (pid=157674) completed reading backup image
8.6.2021 21:55:31 - Info bpdm (pid=157674) EXITING with status 0
8.6.2021 21:55:31 - Info vdc3bck1.mycompany.com (pid=157674) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 105641542 KB, CR received: 28186081 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 21:55:31 - requesting resource  @aaaaw
8.6.2021 21:55:31 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 21:55:33 - Info bptm (pid=158363) start
8.6.2021 21:55:33 - started process bptm (pid=158363)
8.6.2021 21:55:33 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:55:33 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 21:56:54 - granted resource  O002L8
8.6.2021 21:56:54 - granted resource  IBM.ULT3580-HH8.002
8.6.2021 21:56:54 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 21:56:55 - Info bptm (pid=158363) start backup
8.6.2021 21:56:55 - Info bpdm (pid=158531) started
8.6.2021 21:56:55 - started process bpdm (pid=158531)
8.6.2021 21:56:55 - Info bpdm (pid=158531) reading backup image
8.6.2021 21:56:55 - Info bpdm (pid=158531) using 256 data buffers
8.6.2021 21:56:55 - Info bpdm (pid=158531) requesting nbjm for media
8.6.2021 21:56:56 - Info bptm (pid=158363) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 21:56:56 - started process bptm (pid=158363)
8.6.2021 21:56:56 - mounting O002L8
8.6.2021 21:56:56 - Info bptm (pid=158363) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:56:56 - begin reading
8.6.2021 21:57:37 - Info bptm (pid=158363) media id O002L8 mounted on drive index 2, drivepath /dev/tape/by-path/pci-0000:5e:00.0-fc-0x5000e11171beb05c-lun-0-nst, drivename IBM.ULT3580-HH8.002, copy 4
8.6.2021 21:57:37 - Info bptm (pid=158363) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 21:59:02 - Info bptm (pid=158363) waited for full buffer 1145 times, delayed 1302 times
8.6.2021 21:59:04 - end reading; read time: 0:02:08
8.6.2021 21:59:05 - Info bpdm (pid=158531) completed reading backup image
8.6.2021 21:59:05 - Info bpdm (pid=158531) using 256 data buffers
8.6.2021 21:59:05 - begin reading
8.6.2021 22:00:02 - Info bptm (pid=158363) waited for full buffer 1418 times, delayed 2148 times
8.6.2021 22:00:06 - end reading; read time: 0:01:01
8.6.2021 22:00:07 - Info bpdm (pid=158531) completed reading backup image
8.6.2021 22:00:07 - Info bpdm (pid=158531) using 256 data buffers
8.6.2021 22:00:08 - begin reading
8.6.2021 22:00:47 - Info bptm (pid=158363) waited for full buffer 529 times, delayed 796 times
8.6.2021 22:00:49 - end reading; read time: 0:00:41
8.6.2021 22:00:50 - Info bpdm (pid=158531) completed reading backup image
8.6.2021 22:00:50 - Info bpdm (pid=158531) using 256 data buffers
8.6.2021 22:00:51 - begin reading
8.6.2021 22:04:28 - Info bptm (pid=158363) waited for full buffer 4941 times, delayed 8631 times
8.6.2021 22:04:34 - end reading; read time: 0:03:43
8.6.2021 22:04:34 - Info bpdm (pid=158531) completed reading backup image
8.6.2021 22:04:35 - Info bpdm (pid=158531) using 256 data buffers
8.6.2021 22:04:35 - begin reading
8.6.2021 22:05:23 - Info bptm (pid=158363) waited for full buffer 680 times, delayed 1360 times
8.6.2021 22:05:29 - end reading; read time: 0:00:54
8.6.2021 22:05:29 - Info bpdm (pid=158531) completed reading backup image
8.6.2021 22:05:30 - Info bpdm (pid=158531) using 256 data buffers
8.6.2021 22:05:30 - begin reading
8.6.2021 22:12:46 - Info bptm (pid=158363) waited for full buffer 7946 times, delayed 25104 times
8.6.2021 22:12:52 - Info bptm (pid=158363) EXITING with status 0 <----------
8.6.2021 22:12:53 - end reading; read time: 0:07:23
8.6.2021 22:12:53 - Info bpdm (pid=158531) completed reading backup image
8.6.2021 22:12:53 - Info bpdm (pid=158531) EXITING with status 0
8.6.2021 22:12:53 - Info vdc3bck1.mycompany.com (pid=158531) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 105028036 KB, CR received: 61101890 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 22:12:53 - requesting resource  @aaaaw
8.6.2021 22:12:53 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 22:12:54 - Info bptm (pid=159996) start
8.6.2021 22:12:54 - started process bptm (pid=159996)
8.6.2021 22:12:55 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 22:12:55 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 22:15:33 - granted resource  O002L8
8.6.2021 22:15:33 - granted resource  IBM.ULT3580-HH8.000
8.6.2021 22:15:33 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 22:15:34 - Info bptm (pid=159996) start backup
8.6.2021 22:15:34 - Info bpdm (pid=160258) started
8.6.2021 22:15:34 - started process bpdm (pid=160258)
8.6.2021 22:15:34 - Info bpdm (pid=160258) reading backup image
8.6.2021 22:15:34 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:15:34 - Info bpdm (pid=160258) requesting nbjm for media
8.6.2021 22:15:34 - Info bptm (pid=159996) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 22:15:34 - started process bptm (pid=159996)
8.6.2021 22:15:34 - mounting O002L8
8.6.2021 22:15:34 - Info bptm (pid=159996) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 22:15:35 - begin reading
8.6.2021 22:16:14 - Info bptm (pid=159996) media id O002L8 mounted on drive index 0, drivepath /dev/tape/by-path/pci-0000:5e:00.1-fc-0x5000e11171beb066-lun-0-nst, drivename IBM.ULT3580-HH8.000, copy 4
8.6.2021 22:16:14 - Info bptm (pid=159996) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 22:20:58 - Info bptm (pid=159996) waited for full buffer 4147 times, delayed 9202 times
8.6.2021 22:21:00 - end reading; read time: 0:05:25
8.6.2021 22:21:00 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:21:01 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:21:01 - begin reading
8.6.2021 22:21:54 - Info bptm (pid=159996) waited for full buffer 749 times, delayed 1478 times
8.6.2021 22:21:59 - end reading; read time: 0:00:58
8.6.2021 22:21:59 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:22:00 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:22:00 - begin reading
8.6.2021 22:22:46 - Info bptm (pid=159996) waited for full buffer 0 times, delayed 0 times
8.6.2021 22:22:49 - end reading; read time: 0:00:49
8.6.2021 22:22:49 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:22:50 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:22:50 - begin reading
8.6.2021 22:23:23 - Info bptm (pid=159996) waited for full buffer 275 times, delayed 275 times
8.6.2021 22:23:26 - end reading; read time: 0:00:36
8.6.2021 22:23:26 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:23:27 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:23:27 - begin reading
8.6.2021 22:24:25 - Info bptm (pid=159996) waited for full buffer 1554 times, delayed 2172 times
8.6.2021 22:24:31 - end reading; read time: 0:01:04
8.6.2021 22:24:31 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:24:32 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:24:32 - begin reading
8.6.2021 22:24:43 - Info bptm (pid=159996) waited for full buffer 328 times, delayed 494 times
8.6.2021 22:24:47 - end reading; read time: 0:00:15
8.6.2021 22:24:48 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:24:48 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:24:49 - begin reading
8.6.2021 22:24:49 - Info bptm (pid=159996) waited for full buffer 8 times, delayed 25 times
8.6.2021 22:24:50 - end reading; read time: 0:00:01
8.6.2021 22:24:50 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:24:51 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:24:51 - begin reading
8.6.2021 22:24:51 - Info bptm (pid=159996) waited for full buffer 2 times, delayed 2 times
8.6.2021 22:24:52 - end reading; read time: 0:00:01
8.6.2021 22:24:52 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:24:53 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:24:53 - begin reading
8.6.2021 22:24:53 - Info bptm (pid=159996) waited for full buffer 0 times, delayed 0 times
8.6.2021 22:24:54 - end reading; read time: 0:00:01
8.6.2021 22:24:54 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:24:55 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:24:55 - begin reading
8.6.2021 22:24:55 - Info bptm (pid=159996) waited for full buffer 0 times, delayed 0 times
8.6.2021 22:24:56 - end reading; read time: 0:00:01
8.6.2021 22:24:56 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:24:57 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:24:57 - begin reading
8.6.2021 22:24:58 - Info bptm (pid=159996) waited for full buffer 11 times, delayed 47 times
8.6.2021 22:25:02 - end reading; read time: 0:00:05
8.6.2021 22:25:02 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:25:03 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:25:03 - begin reading
8.6.2021 22:25:09 - Info bptm (pid=159996) waited for full buffer 164 times, delayed 280 times
8.6.2021 22:25:14 - end reading; read time: 0:00:11
8.6.2021 22:25:15 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:25:15 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:25:15 - begin reading
8.6.2021 22:25:16 - Info bptm (pid=159996) waited for full buffer 0 times, delayed 0 times
8.6.2021 22:25:17 - end reading; read time: 0:00:02
8.6.2021 22:25:18 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:25:18 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:25:18 - begin reading
8.6.2021 22:25:25 - Info bptm (pid=159996) waited for full buffer 133 times, delayed 245 times
8.6.2021 22:25:29 - end reading; read time: 0:00:11
8.6.2021 22:25:29 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:25:30 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:25:30 - begin reading
8.6.2021 22:25:33 - Info bptm (pid=159996) waited for full buffer 44 times, delayed 73 times
8.6.2021 22:25:38 - end reading; read time: 0:00:08
8.6.2021 22:25:38 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:25:39 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:25:39 - begin reading
8.6.2021 22:25:43 - Info bptm (pid=159996) waited for full buffer 62 times, delayed 79 times
8.6.2021 22:25:45 - end reading; read time: 0:00:06
8.6.2021 22:25:45 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:25:46 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:25:46 - begin reading
8.6.2021 22:25:50 - Info bptm (pid=159996) waited for full buffer 64 times, delayed 110 times
8.6.2021 22:25:51 - end reading; read time: 0:00:05
8.6.2021 22:25:51 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:25:52 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:25:52 - begin reading
8.6.2021 22:25:55 - Info bptm (pid=159996) waited for full buffer 46 times, delayed 61 times
8.6.2021 22:25:57 - end reading; read time: 0:00:05
8.6.2021 22:25:57 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:25:58 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:25:58 - begin reading
8.6.2021 22:26:01 - Info bptm (pid=159996) waited for full buffer 29 times, delayed 29 times
8.6.2021 22:26:03 - end reading; read time: 0:00:05
8.6.2021 22:26:03 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:26:04 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:26:04 - begin reading
8.6.2021 22:26:06 - Info bptm (pid=159996) waited for full buffer 34 times, delayed 62 times
8.6.2021 22:26:07 - end reading; read time: 0:00:03
8.6.2021 22:26:07 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:26:08 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:26:08 - begin reading
8.6.2021 22:26:08 - Info bptm (pid=159996) waited for full buffer 3 times, delayed 7 times
8.6.2021 22:26:09 - end reading; read time: 0:00:01
8.6.2021 22:26:09 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:26:10 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:26:10 - begin reading
8.6.2021 22:26:12 - Info bptm (pid=159996) waited for full buffer 19 times, delayed 29 times
8.6.2021 22:26:13 - end reading; read time: 0:00:03
8.6.2021 22:26:13 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:26:14 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:26:14 - begin reading
8.6.2021 22:26:16 - Info bptm (pid=159996) waited for full buffer 21 times, delayed 30 times
8.6.2021 22:26:17 - end reading; read time: 0:00:03
8.6.2021 22:26:17 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:26:18 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:26:18 - begin reading
8.6.2021 22:26:18 - Info bptm (pid=159996) waited for full buffer 3 times, delayed 12 times
8.6.2021 22:26:25 - end reading; read time: 0:00:07
8.6.2021 22:26:25 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:26:26 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:26:27 - begin reading
8.6.2021 22:26:29 - Info bptm (pid=159996) waited for full buffer 45 times, delayed 50 times
8.6.2021 22:26:32 - end reading; read time: 0:00:05
8.6.2021 22:26:33 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:26:33 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:26:33 - begin reading
8.6.2021 22:26:45 - Info bptm (pid=159996) waited for full buffer 146 times, delayed 214 times
8.6.2021 22:26:46 - end reading; read time: 0:00:13
8.6.2021 22:26:47 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:26:47 - Info bpdm (pid=160258) using 256 data buffers
8.6.2021 22:26:48 - begin reading
8.6.2021 22:27:00 - Info bptm (pid=159996) waited for full buffer 144 times, delayed 199 times
8.6.2021 22:27:06 - Info bptm (pid=159996) EXITING with status 0 <----------
8.6.2021 22:27:07 - end reading; read time: 0:00:19
8.6.2021 22:27:07 - Info bpdm (pid=160258) completed reading backup image
8.6.2021 22:27:07 - Info bpdm (pid=160258) EXITING with status 0
8.6.2021 22:27:07 - Info vdc3bck1.mycompany.com (pid=160258) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 102241137 KB, CR received: 48012415 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 22:27:07 - requesting resource  @aaaaw
8.6.2021 22:27:07 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 22:27:08 - Info bptm (pid=161375) start
8.6.2021 22:27:08 - started process bptm (pid=161375)
8.6.2021 22:27:09 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 22:27:09 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 22:28:11 - granted resource  O002L8
8.6.2021 22:28:11 - granted resource  IBM.ULT3580-HH8.001
8.6.2021 22:28:11 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 22:28:12 - Info bptm (pid=161375) start backup
8.6.2021 22:28:12 - Info bpdm (pid=161451) started
8.6.2021 22:28:13 - started process bpdm (pid=161451)
8.6.2021 22:28:13 - Info bpdm (pid=161451) reading backup image
8.6.2021 22:28:13 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:28:13 - Info bptm (pid=161375) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 22:28:13 - started process bptm (pid=161375)
8.6.2021 22:28:13 - mounting O002L8
8.6.2021 22:28:13 - Info bpdm (pid=161451) requesting nbjm for media
8.6.2021 22:28:13 - Info bptm (pid=161375) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 22:28:14 - begin reading
8.6.2021 22:28:54 - Info bptm (pid=161375) media id O002L8 mounted on drive index 1, drivepath /dev/tape/by-path/pci-0000:5e:00.0-fc-0x5000e11171beb070-lun-0-nst, drivename IBM.ULT3580-HH8.001, copy 4
8.6.2021 22:28:54 - Info bptm (pid=161375) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 22:29:43 - Info bptm (pid=161375) waited for full buffer 523 times, delayed 590 times
8.6.2021 22:29:48 - end reading; read time: 0:01:34
8.6.2021 22:29:48 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:29:49 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:29:49 - begin reading
8.6.2021 22:29:49 - Info bptm (pid=161375) waited for full buffer 0 times, delayed 0 times
8.6.2021 22:29:51 - end reading; read time: 0:00:02
8.6.2021 22:29:51 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:29:52 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:29:52 - begin reading
8.6.2021 22:30:04 - Info bptm (pid=161375) waited for full buffer 128 times, delayed 170 times
8.6.2021 22:30:11 - end reading; read time: 0:00:19
8.6.2021 22:30:11 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:30:11 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:30:12 - begin reading
8.6.2021 22:30:24 - Info bptm (pid=161375) waited for full buffer 162 times, delayed 221 times
8.6.2021 22:30:27 - end reading; read time: 0:00:15
8.6.2021 22:30:27 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:30:28 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:30:28 - begin reading
8.6.2021 22:30:28 - Info bptm (pid=161375) waited for full buffer 0 times, delayed 0 times
8.6.2021 22:30:29 - end reading; read time: 0:00:01
8.6.2021 22:30:29 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:30:30 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:30:30 - begin reading
8.6.2021 22:30:31 - Info bptm (pid=161375) waited for full buffer 26 times, delayed 31 times
8.6.2021 22:30:35 - end reading; read time: 0:00:05
8.6.2021 22:30:35 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:30:36 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:30:36 - begin reading
8.6.2021 22:31:21 - Info bptm (pid=161375) waited for full buffer 1010 times, delayed 1361 times
8.6.2021 22:31:24 - end reading; read time: 0:00:48
8.6.2021 22:31:24 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:31:25 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:31:25 - begin reading
8.6.2021 22:31:25 - Info bptm (pid=161375) waited for full buffer 3 times, delayed 11 times
8.6.2021 22:31:26 - end reading; read time: 0:00:01
8.6.2021 22:31:27 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:31:27 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:31:27 - begin reading
8.6.2021 22:31:31 - Info bptm (pid=161375) waited for full buffer 84 times, delayed 140 times
8.6.2021 22:31:33 - end reading; read time: 0:00:06
8.6.2021 22:31:33 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:31:34 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:31:34 - begin reading
8.6.2021 22:31:55 - Info bptm (pid=161375) waited for full buffer 165 times, delayed 178 times
8.6.2021 22:31:59 - end reading; read time: 0:00:25
8.6.2021 22:31:59 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:32:00 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:32:01 - begin reading
8.6.2021 22:32:07 - Info bptm (pid=161375) waited for full buffer 176 times, delayed 313 times
8.6.2021 22:32:11 - end reading; read time: 0:00:10
8.6.2021 22:32:11 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:32:12 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:32:12 - begin reading
8.6.2021 22:32:12 - Info bptm (pid=161375) waited for full buffer 0 times, delayed 0 times
8.6.2021 22:32:14 - end reading; read time: 0:00:02
8.6.2021 22:32:14 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:32:15 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:32:15 - begin reading
8.6.2021 22:32:50 - Info bptm (pid=161375) waited for full buffer 742 times, delayed 1392 times
8.6.2021 22:32:59 - end reading; read time: 0:00:44
8.6.2021 22:32:59 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:33:00 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:33:00 - begin reading
8.6.2021 22:33:04 - Info bptm (pid=161375) waited for full buffer 94 times, delayed 148 times
8.6.2021 22:33:05 - end reading; read time: 0:00:05
8.6.2021 22:33:05 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:33:06 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:33:06 - begin reading
8.6.2021 22:33:26 - Info bptm (pid=161375) waited for full buffer 573 times, delayed 824 times
8.6.2021 22:33:29 - end reading; read time: 0:00:23
8.6.2021 22:33:30 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:33:30 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:33:31 - begin reading
8.6.2021 22:34:10 - Info bptm (pid=161375) waited for full buffer 729 times, delayed 868 times
8.6.2021 22:34:12 - end reading; read time: 0:00:41
8.6.2021 22:34:12 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:34:13 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:34:13 - begin reading
8.6.2021 22:34:19 - Info bptm (pid=161375) waited for full buffer 74 times, delayed 109 times
8.6.2021 22:34:23 - end reading; read time: 0:00:10
8.6.2021 22:34:23 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:34:24 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:34:24 - begin reading
8.6.2021 22:35:08 - Info bptm (pid=161375) waited for full buffer 980 times, delayed 1260 times
8.6.2021 22:35:11 - end reading; read time: 0:00:47
8.6.2021 22:35:11 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:35:12 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:35:12 - begin reading
8.6.2021 22:35:29 - Info bptm (pid=161375) waited for full buffer 228 times, delayed 247 times
8.6.2021 22:35:32 - end reading; read time: 0:00:20
8.6.2021 22:35:32 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:35:33 - Info bpdm (pid=161451) using 256 data buffers
8.6.2021 22:35:33 - begin reading
8.6.2021 22:36:11 - Info bptm (pid=161375) waited for full buffer 513 times, delayed 593 times
8.6.2021 22:36:17 - Info bptm (pid=161375) EXITING with status 0 <----------
8.6.2021 22:36:18 - end reading; read time: 0:00:45
8.6.2021 22:36:18 - Info bpdm (pid=161451) completed reading backup image
8.6.2021 22:36:18 - Info bpdm (pid=161451) EXITING with status 0
8.6.2021 22:36:18 - Info vdc3bck1.mycompany.com (pid=161451) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 102575757 KB, CR received: 30431931 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 22:36:18 - requesting resource  @aaaaw
8.6.2021 22:36:18 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 22:36:19 - Info bptm (pid=162283) start
8.6.2021 22:36:20 - started process bptm (pid=162283)
8.6.2021 22:36:20 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 22:36:20 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 22:38:43 - granted resource  O002L8
8.6.2021 22:38:43 - granted resource  IBM.ULT3580-HH8.002
8.6.2021 22:38:43 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 22:38:44 - Info bptm (pid=162283) start backup
8.6.2021 22:38:44 - Info bpdm (pid=162453) started
8.6.2021 22:38:44 - started process bpdm (pid=162453)
8.6.2021 22:38:44 - Info bpdm (pid=162453) reading backup image
8.6.2021 22:38:44 - Info bpdm (pid=162453) using 256 data buffers
8.6.2021 22:38:44 - Info bptm (pid=162283) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 22:38:44 - started process bptm (pid=162283)
8.6.2021 22:38:44 - mounting O002L8
8.6.2021 22:38:44 - Info bpdm (pid=162453) requesting nbjm for media
8.6.2021 22:38:44 - Info bptm (pid=162283) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 22:38:45 - begin reading
8.6.2021 22:39:25 - Info bptm (pid=162283) media id O002L8 mounted on drive index 2, drivepath /dev/tape/by-path/pci-0000:5e:00.0-fc-0x5000e11171beb05c-lun-0-nst, drivename IBM.ULT3580-HH8.002, copy 4
8.6.2021 22:39:25 - Info bptm (pid=162283) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 22:41:14 - Info bptm (pid=162283) waited for full buffer 1229 times, delayed 1301 times
8.6.2021 22:41:18 - end reading; read time: 0:02:33
8.6.2021 22:41:18 - Info bpdm (pid=162453) completed reading backup image
8.6.2021 22:41:19 - Info bpdm (pid=162453) using 256 data buffers
8.6.2021 22:41:19 - begin reading
8.6.2021 22:41:37 - Info bptm (pid=162283) waited for full buffer 436 times, delayed 470 times
8.6.2021 22:41:40 - end reading; read time: 0:00:21
8.6.2021 22:41:41 - Info bpdm (pid=162453) completed reading backup image
8.6.2021 22:41:41 - Info bpdm (pid=162453) using 256 data buffers
8.6.2021 22:41:41 - begin reading
8.6.2021 22:42:43 - Info bptm (pid=162283) waited for full buffer 1589 times, delayed 2209 times
8.6.2021 22:42:49 - end reading; read time: 0:01:08
8.6.2021 22:42:50 - Info bpdm (pid=162453) completed reading backup image
8.6.2021 22:42:51 - Info bpdm (pid=162453) using 256 data buffers
8.6.2021 22:42:51 - begin reading
8.6.2021 22:43:45 - Info bptm (pid=162283) waited for full buffer 1265 times, delayed 1976 times
8.6.2021 22:43:47 - end reading; read time: 0:00:56
8.6.2021 22:43:47 - Info bpdm (pid=162453) completed reading backup image
8.6.2021 22:43:48 - Info bpdm (pid=162453) using 256 data buffers
8.6.2021 22:43:48 - begin reading
8.6.2021 22:44:16 - Info bptm (pid=162283) waited for full buffer 618 times, delayed 712 times
8.6.2021 22:44:18 - end reading; read time: 0:00:30
8.6.2021 22:44:18 - Info bpdm (pid=162453) completed reading backup image
8.6.2021 22:44:19 - Info bpdm (pid=162453) using 256 data buffers
8.6.2021 22:44:19 - begin reading
8.6.2021 22:44:55 - Info bptm (pid=162283) waited for full buffer 958 times, delayed 1089 times
8.6.2021 22:44:58 - end reading; read time: 0:00:39
8.6.2021 22:44:58 - Info bpdm (pid=162453) completed reading backup image
8.6.2021 22:44:59 - Info bpdm (pid=162453) using 256 data buffers
8.6.2021 22:44:59 - begin reading
8.6.2021 22:45:57 - Info bptm (pid=162283) waited for full buffer 1686 times, delayed 2166 times
8.6.2021 22:45:58 - end reading; read time: 0:00:59
8.6.2021 22:45:58 - Info bpdm (pid=162453) completed reading backup image
8.6.2021 22:45:59 - Info bpdm (pid=162453) using 256 data buffers
8.6.2021 22:45:59 - begin reading
8.6.2021 22:46:44 - Info bptm (pid=162283) waited for full buffer 1113 times, delayed 1224 times
8.6.2021 22:46:49 - end reading; read time: 0:00:50
8.6.2021 22:46:49 - Info bpdm (pid=162453) completed reading backup image
8.6.2021 22:46:50 - Info bpdm (pid=162453) using 256 data buffers
8.6.2021 22:46:50 - begin reading
8.6.2021 22:47:24 - Info bptm (pid=162283) waited for full buffer 900 times, delayed 967 times
8.6.2021 22:47:34 - Info bptm (pid=162283) EXITING with status 0 <----------
8.6.2021 22:47:34 - end reading; read time: 0:00:44
8.6.2021 22:47:34 - Info bpdm (pid=162453) completed reading backup image
8.6.2021 22:47:34 - Info bpdm (pid=162453) EXITING with status 0
8.6.2021 22:47:34 - Info vdc3bck1.mycompany.com (pid=162453) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 106321702 KB, CR received: 30948207 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 22:47:34 - requesting resource  @aaaaw
8.6.2021 22:47:34 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 22:47:36 - Info bptm (pid=163333) start
8.6.2021 22:47:36 - started process bptm (pid=163333)
8.6.2021 22:47:36 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 22:47:36 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 22:50:10 - granted resource  O002L8
8.6.2021 22:50:10 - granted resource  IBM.ULT3580-HH8.000
8.6.2021 22:50:10 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 22:50:11 - Info bptm (pid=163333) start backup
8.6.2021 22:50:11 - Info bpdm (pid=163569) started
8.6.2021 22:50:11 - started process bpdm (pid=163569)
8.6.2021 22:50:12 - Info bpdm (pid=163569) reading backup image
8.6.2021 22:50:12 - Info bpdm (pid=163569) using 256 data buffers
8.6.2021 22:50:12 - Info bptm (pid=163333) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 22:50:12 - started process bptm (pid=163333)
8.6.2021 22:50:12 - mounting O002L8
8.6.2021 22:50:12 - Info bpdm (pid=163569) requesting nbjm for media
8.6.2021 22:50:12 - Info bptm (pid=163333) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 22:50:12 - begin reading
8.6.2021 22:50:52 - Info bptm (pid=163333) media id O002L8 mounted on drive index 0, drivepath /dev/tape/by-path/pci-0000:5e:00.1-fc-0x5000e11171beb066-lun-0-nst, drivename IBM.ULT3580-HH8.000, copy 4
8.6.2021 22:50:52 - Info bptm (pid=163333) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 22:53:38 - Info bptm (pid=163333) waited for full buffer 1366 times, delayed 1429 times
8.6.2021 22:53:41 - end reading; read time: 0:03:29
8.6.2021 22:53:41 - Info bpdm (pid=163569) completed reading backup image
8.6.2021 22:53:42 - Info bpdm (pid=163569) using 256 data buffers
8.6.2021 22:53:42 - begin reading
8.6.2021 22:54:31 - Info bptm (pid=163333) waited for full buffer 1253 times, delayed 1679 times
8.6.2021 22:54:34 - end reading; read time: 0:00:52
8.6.2021 22:54:34 - Info bpdm (pid=163569) completed reading backup image
8.6.2021 22:54:35 - Info bpdm (pid=163569) using 256 data buffers
8.6.2021 22:54:35 - begin reading
8.6.2021 22:55:02 - Info bptm (pid=163333) waited for full buffer 430 times, delayed 515 times
8.6.2021 22:55:07 - end reading; read time: 0:00:32
8.6.2021 22:55:07 - Info bpdm (pid=163569) completed reading backup image
8.6.2021 22:55:07 - Info bpdm (pid=163569) using 256 data buffers
8.6.2021 22:55:08 - begin reading
8.6.2021 22:55:34 - Info bptm (pid=163333) waited for full buffer 400 times, delayed 507 times
8.6.2021 22:55:39 - end reading; read time: 0:00:31
8.6.2021 22:55:39 - Info bpdm (pid=163569) completed reading backup image
8.6.2021 22:55:40 - Info bpdm (pid=163569) using 256 data buffers
8.6.2021 22:55:40 - begin reading
8.6.2021 22:56:12 - Info bptm (pid=163333) waited for full buffer 504 times, delayed 596 times
8.6.2021 22:56:13 - end reading; read time: 0:00:33
8.6.2021 22:56:14 - Info bpdm (pid=163569) completed reading backup image
8.6.2021 22:56:14 - Info bpdm (pid=163569) using 256 data buffers
8.6.2021 22:56:15 - begin reading
8.6.2021 22:56:56 - Info bptm (pid=163333) waited for full buffer 748 times, delayed 989 times
8.6.2021 22:57:02 - end reading; read time: 0:00:47
8.6.2021 22:57:02 - Info bpdm (pid=163569) completed reading backup image
8.6.2021 22:57:03 - Info bpdm (pid=163569) using 256 data buffers
8.6.2021 22:57:03 - begin reading
8.6.2021 22:57:44 - Info bptm (pid=163333) waited for full buffer 762 times, delayed 1051 times
8.6.2021 22:57:47 - end reading; read time: 0:00:44
8.6.2021 22:57:47 - Info bpdm (pid=163569) completed reading backup image
8.6.2021 22:57:47 - Info bpdm (pid=163569) using 256 data buffers
8.6.2021 22:57:48 - begin reading
8.6.2021 22:58:14 - Info bptm (pid=163333) waited for full buffer 381 times, delayed 479 times
8.6.2021 22:58:24 - Info bptm (pid=163333) EXITING with status 0 <----------
8.6.2021 22:58:24 - end reading; read time: 0:00:36
8.6.2021 22:58:24 - Info bpdm (pid=163569) completed reading backup image
8.6.2021 22:58:24 - Info bpdm (pid=163569) EXITING with status 0
8.6.2021 22:58:24 - Info vdc3bck1.mycompany.com (pid=163569) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 102631173 KB, CR received: 30127618 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 22:58:24 - requesting resource  @aaaaw
8.6.2021 22:58:24 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 22:58:25 - Info bptm (pid=164286) start
8.6.2021 22:58:26 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 22:58:26 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 23:00:16 - Info bptm (pid=164286) start backup
8.6.2021 23:00:16 - granted resource  O002L8
8.6.2021 23:00:16 - granted resource  IBM.ULT3580-HH8.001
8.6.2021 23:00:16 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 23:00:17 - Info bpdm (pid=164540) started
8.6.2021 23:00:17 - started process bpdm (pid=164540)
8.6.2021 23:00:17 - Info bpdm (pid=164540) reading backup image
8.6.2021 23:00:17 - Info bpdm (pid=164540) using 256 data buffers
8.6.2021 23:00:17 - Info bptm (pid=164286) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 23:00:17 - started process bptm (pid=164286)
8.6.2021 23:00:17 - mounting O002L8
8.6.2021 23:00:17 - Info bptm (pid=164286) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 23:00:17 - Info bpdm (pid=164540) requesting nbjm for media
8.6.2021 23:00:18 - begin reading
8.6.2021 23:00:58 - Info bptm (pid=164286) media id O002L8 mounted on drive index 1, drivepath /dev/tape/by-path/pci-0000:5e:00.0-fc-0x5000e11171beb070-lun-0-nst, drivename IBM.ULT3580-HH8.001, copy 4
8.6.2021 23:00:59 - Info bptm (pid=164286) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 23:02:59 - Info bptm (pid=164286) waited for full buffer 1625 times, delayed 1808 times
8.6.2021 23:03:03 - end reading; read time: 0:02:45
8.6.2021 23:03:03 - Info bpdm (pid=164540) completed reading backup image
8.6.2021 23:03:04 - Info bpdm (pid=164540) using 256 data buffers
8.6.2021 23:03:04 - begin reading
8.6.2021 23:03:45 - Info bptm (pid=164286) waited for full buffer 832 times, delayed 1074 times
8.6.2021 23:03:47 - end reading; read time: 0:00:43
8.6.2021 23:03:47 - Info bpdm (pid=164540) completed reading backup image
8.6.2021 23:03:48 - Info bpdm (pid=164540) using 256 data buffers
8.6.2021 23:03:48 - begin reading
8.6.2021 23:04:17 - Info bptm (pid=164286) waited for full buffer 577 times, delayed 675 times
8.6.2021 23:04:20 - end reading; read time: 0:00:32
8.6.2021 23:04:20 - Info bpdm (pid=164540) completed reading backup image
8.6.2021 23:04:21 - Info bpdm (pid=164540) using 256 data buffers
8.6.2021 23:04:21 - begin reading
8.6.2021 23:04:53 - Info bptm (pid=164286) waited for full buffer 447 times, delayed 530 times
8.6.2021 23:04:56 - end reading; read time: 0:00:35
8.6.2021 23:04:57 - Info bpdm (pid=164540) completed reading backup image
8.6.2021 23:04:57 - Info bpdm (pid=164540) using 256 data buffers
8.6.2021 23:04:57 - begin reading
8.6.2021 23:05:37 - Info bptm (pid=164286) waited for full buffer 857 times, delayed 1031 times
8.6.2021 23:05:41 - end reading; read time: 0:00:44
8.6.2021 23:05:41 - Info bpdm (pid=164540) completed reading backup image
8.6.2021 23:05:42 - Info bpdm (pid=164540) using 256 data buffers
8.6.2021 23:05:42 - begin reading
8.6.2021 23:06:37 - Info bptm (pid=164286) waited for full buffer 1555 times, delayed 2063 times
8.6.2021 23:06:41 - end reading; read time: 0:00:59
8.6.2021 23:06:42 - Info bpdm (pid=164540) completed reading backup image
8.6.2021 23:06:42 - Info bpdm (pid=164540) using 256 data buffers
8.6.2021 23:06:42 - begin reading
8.6.2021 23:07:10 - Info bptm (pid=164286) waited for full buffer 462 times, delayed 568 times
8.6.2021 23:07:14 - end reading; read time: 0:00:32
8.6.2021 23:07:14 - Info bpdm (pid=164540) completed reading backup image
8.6.2021 23:07:15 - Info bpdm (pid=164540) using 256 data buffers
8.6.2021 23:07:15 - begin reading
8.6.2021 23:08:44 - Info bptm (pid=164286) waited for full buffer 2211 times, delayed 4436 times
8.6.2021 23:08:53 - Info bptm (pid=164286) EXITING with status 0 <----------
8.6.2021 23:08:53 - end reading; read time: 0:01:38
8.6.2021 23:08:54 - Info bpdm (pid=164540) completed reading backup image
8.6.2021 23:08:54 - Info bpdm (pid=164540) EXITING with status 0
8.6.2021 23:08:54 - Info vdc3bck1.mycompany.com (pid=164540) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 101652229 KB, CR received: 30238593 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 23:08:54 - requesting resource  @aaaaw
8.6.2021 23:08:54 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 23:08:55 - Info bptm (pid=165241) start
8.6.2021 23:08:55 - started process bptm (pid=165241)
8.6.2021 23:08:55 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 23:08:55 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 23:09:58 - granted resource  O002L8
8.6.2021 23:09:58 - granted resource  IBM.ULT3580-HH8.002
8.6.2021 23:09:58 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 23:09:59 - Info bptm (pid=165241) start backup
8.6.2021 23:09:59 - Info bpdm (pid=165313) started
8.6.2021 23:09:59 - started process bpdm (pid=165313)
8.6.2021 23:09:59 - Info bpdm (pid=165313) reading backup image
8.6.2021 23:10:00 - Info bpdm (pid=165313) using 256 data buffers
8.6.2021 23:10:00 - Info bpdm (pid=165313) requesting nbjm for media
8.6.2021 23:10:00 - Info bptm (pid=165241) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 23:10:00 - started process bptm (pid=165241)
8.6.2021 23:10:00 - mounting O002L8
8.6.2021 23:10:00 - Info bptm (pid=165241) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 23:10:00 - begin reading
8.6.2021 23:10:41 - Info bptm (pid=165241) media id O002L8 mounted on drive index 2, drivepath /dev/tape/by-path/pci-0000:5e:00.0-fc-0x5000e11171beb05c-lun-0-nst, drivename IBM.ULT3580-HH8.002, copy 4
8.6.2021 23:10:41 - Info bptm (pid=165241) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 23:12:16 - Info bptm (pid=165241) waited for full buffer 2357 times, delayed 2804 times
8.6.2021 23:12:22 - end reading; read time: 0:02:22
8.6.2021 23:12:22 - Info bpdm (pid=165313) completed reading backup image
8.6.2021 23:12:23 - Info bpdm (pid=165313) using 256 data buffers
8.6.2021 23:12:23 - begin reading
8.6.2021 23:13:07 - Info bptm (pid=165241) waited for full buffer 870 times, delayed 1061 times
8.6.2021 23:13:12 - end reading; read time: 0:00:49
8.6.2021 23:13:12 - Info bpdm (pid=165313) completed reading backup image
8.6.2021 23:13:13 - Info bpdm (pid=165313) using 256 data buffers
8.6.2021 23:13:13 - begin reading
8.6.2021 23:13:47 - Info bptm (pid=165241) waited for full buffer 749 times, delayed 946 times
8.6.2021 23:13:50 - end reading; read time: 0:00:37
8.6.2021 23:13:50 - Info bpdm (pid=165313) completed reading backup image
8.6.2021 23:13:51 - Info bpdm (pid=165313) using 256 data buffers
8.6.2021 23:13:51 - begin reading
8.6.2021 23:14:29 - Info bptm (pid=165241) waited for full buffer 671 times, delayed 868 times
8.6.2021 23:14:33 - end reading; read time: 0:00:42
8.6.2021 23:14:33 - Info bpdm (pid=165313) completed reading backup image
8.6.2021 23:14:34 - Info bpdm (pid=165313) using 256 data buffers
8.6.2021 23:14:34 - begin reading
8.6.2021 23:15:18 - Info bptm (pid=165241) waited for full buffer 923 times, delayed 1176 times
8.6.2021 23:15:21 - end reading; read time: 0:00:47
8.6.2021 23:15:21 - Info bpdm (pid=165313) completed reading backup image
8.6.2021 23:15:22 - Info bpdm (pid=165313) using 256 data buffers
8.6.2021 23:15:22 - begin reading
8.6.2021 23:15:22 - Info bptm (pid=165241) waited for full buffer 4 times, delayed 12 times
8.6.2021 23:15:24 - end reading; read time: 0:00:02
8.6.2021 23:15:24 - Info bpdm (pid=165313) completed reading backup image
8.6.2021 23:15:25 - Info bpdm (pid=165313) using 256 data buffers
8.6.2021 23:15:25 - begin reading
8.6.2021 23:16:05 - Info bptm (pid=165241) waited for full buffer 643 times, delayed 876 times
8.6.2021 23:16:10 - end reading; read time: 0:00:45
8.6.2021 23:16:10 - Info bpdm (pid=165313) completed reading backup image
8.6.2021 23:16:11 - Info bpdm (pid=165313) using 256 data buffers
8.6.2021 23:16:11 - begin reading
8.6.2021 23:16:12 - Info bptm (pid=165241) waited for full buffer 0 times, delayed 0 times
8.6.2021 23:16:14 - end reading; read time: 0:00:03
8.6.2021 23:16:14 - Info bpdm (pid=165313) completed reading backup image
8.6.2021 23:16:15 - Info bpdm (pid=165313) using 256 data buffers
8.6.2021 23:16:15 - begin reading
8.6.2021 23:17:03 - Info bptm (pid=165241) waited for full buffer 946 times, delayed 1252 times
8.6.2021 23:17:08 - end reading; read time: 0:00:53
8.6.2021 23:17:08 - Info bpdm (pid=165313) completed reading backup image
8.6.2021 23:17:09 - Info bpdm (pid=165313) using 256 data buffers
8.6.2021 23:17:09 - begin reading
8.6.2021 23:17:57 - Info bptm (pid=165241) waited for full buffer 1077 times, delayed 1875 times
8.6.2021 23:18:07 - Info bptm (pid=165241) EXITING with status 0 <----------
8.6.2021 23:18:07 - end reading; read time: 0:00:58
8.6.2021 23:18:08 - Info bpdm (pid=165313) completed reading backup image
8.6.2021 23:18:08 - Info bpdm (pid=165313) EXITING with status 0
8.6.2021 23:18:08 - Info vdc3bck1.mycompany.com (pid=165313) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 109215558 KB, CR received: 35306812 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 23:18:08 - requesting resource  @aaaaw
8.6.2021 23:18:08 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 23:18:09 - Info bptm (pid=166190) start
8.6.2021 23:18:09 - started process bptm (pid=166190)
8.6.2021 23:18:09 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 23:18:09 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 23:20:09 - granted resource  O002L8
8.6.2021 23:20:09 - granted resource  IBM.ULT3580-HH8.000
8.6.2021 23:20:09 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 23:20:09 - Info bptm (pid=166190) start backup
8.6.2021 23:20:10 - Info bpdm (pid=166395) started
8.6.2021 23:20:10 - started process bpdm (pid=166395)
8.6.2021 23:20:10 - Info bpdm (pid=166395) reading backup image
8.6.2021 23:20:10 - Info bpdm (pid=166395) using 256 data buffers
8.6.2021 23:20:10 - Info bptm (pid=166190) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 23:20:10 - started process bptm (pid=166190)
8.6.2021 23:20:10 - mounting O002L8
8.6.2021 23:20:10 - Info bpdm (pid=166395) requesting nbjm for media
8.6.2021 23:20:10 - Info bptm (pid=166190) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 23:20:11 - begin reading
8.6.2021 23:20:49 - Info bptm (pid=166190) media id O002L8 mounted on drive index 0, drivepath /dev/tape/by-path/pci-0000:5e:00.1-fc-0x5000e11171beb066-lun-0-nst, drivename IBM.ULT3580-HH8.000, copy 4
8.6.2021 23:20:49 - Info bptm (pid=166190) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 23:22:06 - Info bptm (pid=166190) waited for full buffer 0 times, delayed 0 times
8.6.2021 23:22:09 - end reading; read time: 0:01:58
8.6.2021 23:22:09 - Info bpdm (pid=166395) completed reading backup image
8.6.2021 23:22:10 - Info bpdm (pid=166395) using 256 data buffers
8.6.2021 23:22:10 - begin reading
8.6.2021 23:23:24 - Info bptm (pid=166190) waited for full buffer 2443 times, delayed 3032 times
8.6.2021 23:23:27 - end reading; read time: 0:01:17
8.6.2021 23:23:27 - Info bpdm (pid=166395) completed reading backup image
8.6.2021 23:23:28 - Info bpdm (pid=166395) using 256 data buffers
8.6.2021 23:23:28 - begin reading
8.6.2021 23:23:31 - Info bptm (pid=166190) waited for full buffer 110 times, delayed 115 times
8.6.2021 23:23:32 - end reading; read time: 0:00:04
8.6.2021 23:23:33 - Info bpdm (pid=166395) completed reading backup image
8.6.2021 23:23:33 - Info bpdm (pid=166395) using 256 data buffers
8.6.2021 23:23:34 - begin reading
8.6.2021 23:23:36 - Info bptm (pid=166190) waited for full buffer 80 times, delayed 93 times
8.6.2021 23:23:37 - end reading; read time: 0:00:03
8.6.2021 23:23:38 - Info bpdm (pid=166395) completed reading backup image
8.6.2021 23:23:38 - Info bpdm (pid=166395) using 256 data buffers
8.6.2021 23:23:38 - begin reading
8.6.2021 23:24:29 - Info bptm (pid=166190) waited for full buffer 1129 times, delayed 1569 times
8.6.2021 23:24:33 - end reading; read time: 0:00:55
8.6.2021 23:24:33 - Info bpdm (pid=166395) completed reading backup image
8.6.2021 23:24:33 - Info bpdm (pid=166395) using 256 data buffers
8.6.2021 23:24:34 - begin reading
8.6.2021 23:31:03 - end reading; read time: 0:06:29
8.6.2021 23:31:04 - begin reading
8.6.2021 23:34:22 - Info bptm (pid=166190) waited for full buffer 17044 times, delayed 23090 times
8.6.2021 23:34:31 - Info bptm (pid=166190) EXITING with status 0 <----------
8.6.2021 23:34:31 - end reading; read time: 0:03:27
8.6.2021 23:34:31 - Info bpdm (pid=166395) completed reading backup image
8.6.2021 23:34:31 - Info bpdm (pid=166395) EXITING with status 0
8.6.2021 23:34:31 - Info vdc3bck1.mycompany.com (pid=166395) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 110164164 KB, CR received: 87262987 KB, CR received over FC: 0 KB, dedup: 0.0%
8.6.2021 23:34:31 - requesting resource  @aaaaw
8.6.2021 23:34:31 - granted resource  MediaID=@aaaaw;DiskVolume=PureDiskVolume;DiskPool=Pool4;Path=PureDiskVolume;StorageServer=vdc3bck1.mycompany.com;MediaServer=vdc3bck1.mycompany.com
8.6.2021 23:34:33 - Info bptm (pid=167646) start
8.6.2021 23:34:33 - started process bptm (pid=167646)
8.6.2021 23:34:33 - requesting resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 23:34:33 - awaiting resource vdc3bck1-hcart2-robot-tld-1. Waiting for resources. 
          Reason: Maximum partially full volumes has been reached, Media server: vdc3bck1.mycompany.com, 
          Robot Type(Number): TLD(1), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: NetBackup, Storage Unit: vdc3bck1-hcart2-robot-tld-1, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
8.6.2021 23:36:04 - granted resource  O002L8
8.6.2021 23:36:04 - granted resource  IBM.ULT3580-HH8.001
8.6.2021 23:36:04 - granted resource  vdc3bck1-hcart2-robot-tld-1
8.6.2021 23:36:05 - Info bptm (pid=167646) start backup
8.6.2021 23:36:05 - Info bpdm (pid=167832) started
8.6.2021 23:36:05 - started process bpdm (pid=167832)
8.6.2021 23:36:05 - Info bpdm (pid=167832) reading backup image
8.6.2021 23:36:05 - Info bpdm (pid=167832) using 256 data buffers
8.6.2021 23:36:05 - Info bptm (pid=167646) Waiting for mount of media id O002L8 (copy 4) on server vdc3bck1.mycompany.com.
8.6.2021 23:36:05 - started process bptm (pid=167646)
8.6.2021 23:36:05 - mounting O002L8
8.6.2021 23:36:05 - Info bpdm (pid=167832) requesting nbjm for media
8.6.2021 23:36:05 - Info bptm (pid=167646) INF - Waiting for mount of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 23:36:06 - begin reading
8.6.2021 23:36:47 - Info bptm (pid=167646) media id O002L8 mounted on drive index 1, drivepath /dev/tape/by-path/pci-0000:5e:00.0-fc-0x5000e11171beb070-lun-0-nst, drivename IBM.ULT3580-HH8.001, copy 4
8.6.2021 23:36:47 - Info bptm (pid=167646) INF - Waiting for positioning of media id O002L8 on server vdc3bck1.mycompany.com for writing.
8.6.2021 23:38:21 - Info bptm (pid=167646) waited for full buffer 1360 times, delayed 1443 times
8.6.2021 23:38:23 - end reading; read time: 0:02:17
8.6.2021 23:38:23 - Info bpdm (pid=167832) completed reading backup image
8.6.2021 23:38:24 - Info bpdm (pid=167832) using 256 data buffers
8.6.2021 23:38:24 - begin reading
8.6.2021 23:39:55 - Info bptm (pid=167646) waited for full buffer 2215 times, delayed 2909 times
8.6.2021 23:39:57 - end reading; read time: 0:01:33
8.6.2021 23:39:57 - Info bpdm (pid=167832) completed reading backup image
8.6.2021 23:39:58 - Info bpdm (pid=167832) using 256 data buffers
8.6.2021 23:39:58 - begin reading
8.6.2021 23:40:01 - Info bptm (pid=167646) waited for full buffer 71 times, delayed 74 times
8.6.2021 23:40:03 - end reading; read time: 0:00:05
8.6.2021 23:40:03 - Info bpdm (pid=167832) completed reading backup image
8.6.2021 23:40:04 - Info bpdm (pid=167832) using 256 data buffers
8.6.2021 23:40:04 - begin reading
8.6.2021 23:40:07 - Info bptm (pid=167646) waited for full buffer 95 times, delayed 99 times
8.6.2021 23:40:14 - Info bptm (pid=167646) EXITING with status 0 <----------
8.6.2021 23:40:14 - end reading; read time: 0:00:10
8.6.2021 23:40:14 - Info bpdm (pid=167832) completed reading backup image
8.6.2021 23:40:15 - end Duplicate; elapsed time 2:40:14
8.6.2021 23:40:15 - Info bpdm (pid=167832) EXITING with status 0
8.6.2021 23:40:15 - Info vdc3bck1.mycompany.com (pid=167832) StorageServer=PureDisk:vdc3bck1.mycompany.com; Report=PDDO Stats for (vdc3bck1.mycompany.com): read: 33745538 KB, CR received: 17980196 KB, CR received over FC: 0 KB, dedup: 0.0%
the requested operation was successfully completed  (0)