| Time | Event |
|---|---|
| 2016-06-17 23:05:00 | bacula-dir JobId 23428: No prior Full backup Job record found. |
| 2016-06-17 23:05:00 | bacula-dir JobId 23428: No prior or suitable Full backup found in catalog. Doing FULL backup. |
| 2016-06-17 23:47:24 | bacula-sd JobId 23428: Spooling data ... |
| 2016-06-17 23:47:24 | bacula-dir JobId 23428: Start Backup JobId 23428, Job=BackMonitor.2016-06-17_23.05.00_30 |
| 2016-06-17 23:47:24 | bacula-dir JobId 23428: Using Device "ULT3580-TD3" |
| 2016-06-18 00:29:37 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 00:29:37 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,595 bytes ... |
| 2016-06-18 00:32:30 | bacula-sd JobId 23428: Despooling elapsed time = 00:02:53, Transfer rate = 57.80 M Bytes/second |
| 2016-06-18 00:32:31 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 01:15:13 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 01:15:13 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,628 bytes ... |
| 2016-06-18 01:18:08 | bacula-sd JobId 23428: Despooling elapsed time = 00:02:55, Transfer rate = 57.14 M Bytes/second |
| 2016-06-18 01:18:09 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 02:02:23 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 02:02:23 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,675 bytes ... |
| 2016-06-18 02:05:19 | bacula-sd JobId 23428: Despooling elapsed time = 00:02:56, Transfer rate = 56.81 M Bytes/second |
| 2016-06-18 02:05:20 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 02:47:37 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 02:47:37 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,661 bytes ... |
| 2016-06-18 02:50:32 | bacula-sd JobId 23428: Despooling elapsed time = 00:02:55, Transfer rate = 57.14 M Bytes/second |
| 2016-06-18 02:50:32 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 03:32:31 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 03:32:31 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,656 bytes ... |
| 2016-06-18 03:35:31 | bacula-sd JobId 23428: Despooling elapsed time = 00:03:00, Transfer rate = 55.55 M Bytes/second |
| 2016-06-18 03:35:32 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 04:17:55 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 04:17:55 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,624 bytes ... |
| 2016-06-18 04:20:49 | bacula-sd JobId 23428: Despooling elapsed time = 00:02:54, Transfer rate = 57.47 M Bytes/second |
| 2016-06-18 04:20:49 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 05:02:59 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 05:02:59 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,594 bytes ... |
| 2016-06-18 05:03:17 | bacula-sd JobId 23428: End of Volume "L30007L3" at 504:613 on device "ULT3580-TD3" (/dev/nst1). Write of 262144 bytes got -1. |
| 2016-06-18 05:03:20 | bacula-sd JobId 23428: Re-read of last block succeeded. |
| 2016-06-18 05:03:20 | bacula-sd JobId 23428: End of medium on Volume "L30007L3" Bytes=457,853,087,744 Blocks=1,746,604 at 18-Jun-2016 05:03. |
| 2016-06-18 05:03:20 | bacula-sd JobId 23428: 3307 Issuing autochanger "unload slot 8, drive 1" command. |
| 2016-06-18 05:03:59 | bacula-sd JobId 23428: 3301 Issuing autochanger "loaded? drive 1" command. |
| 2016-06-18 05:04:01 | bacula-sd JobId 23428: 3302 Autochanger "loaded? drive 1", result: nothing loaded. |
| 2016-06-18 05:04:01 | bacula-sd JobId 23428: 3301 Issuing autochanger "loaded? drive 1" command. |
| 2016-06-18 05:04:03 | bacula-sd JobId 23428: 3302 Autochanger "loaded? drive 1", result: nothing loaded. |
| 2016-06-18 05:04:03 | bacula-sd JobId 23428: 3304 Issuing autochanger "load slot 18, drive 1" command. |
| 2016-06-18 05:04:17 | bacula-sd JobId 23428: 3305 Autochanger "load slot 18, drive 1", status is OK. |
| 2016-06-18 05:04:20 | bacula-sd JobId 23428: Wrote label to prelabeled Volume "L30017L3" on device "ULT3580-TD3" (/dev/nst1) |
| 2016-06-18 05:04:20 | bacula-sd JobId 23428: New volume "L30017L3" mounted on device "ULT3580-TD3" (/dev/nst1) at 18-Jun-2016 05:04. |
| 2016-06-18 05:06:50 | bacula-sd JobId 23428: Despooling elapsed time = 00:02:51, Transfer rate = 58.48 M Bytes/second |
| 2016-06-18 05:06:50 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 05:50:11 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 05:50:11 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,705 bytes ... |
| 2016-06-18 05:53:21 | bacula-sd JobId 23428: Despooling elapsed time = 00:03:10, Transfer rate = 52.63 M Bytes/second |
| 2016-06-18 05:53:21 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 06:35:19 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 06:35:19 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,657 bytes ... |
| 2016-06-18 06:38:11 | bacula-sd JobId 23428: Despooling elapsed time = 00:02:52, Transfer rate = 58.14 M Bytes/second |
| 2016-06-18 06:38:12 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 07:20:22 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 07:20:22 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,660 bytes ... |
| 2016-06-18 07:23:24 | bacula-sd JobId 23428: Despooling elapsed time = 00:03:02, Transfer rate = 54.94 M Bytes/second |
| 2016-06-18 07:23:24 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 08:05:09 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 08:05:09 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,658 bytes ... |
| 2016-06-18 08:08:22 | bacula-sd JobId 23428: Despooling elapsed time = 00:03:13, Transfer rate = 51.81 M Bytes/second |
| 2016-06-18 08:08:23 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 08:49:00 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 08:49:00 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,648 bytes ... |
| 2016-06-18 08:51:56 | bacula-sd JobId 23428: Despooling elapsed time = 00:02:56, Transfer rate = 56.81 M Bytes/second |
| 2016-06-18 08:51:57 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 09:32:12 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 09:32:12 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,687 bytes ... |
| 2016-06-18 09:35:25 | bacula-sd JobId 23428: Despooling elapsed time = 00:03:13, Transfer rate = 51.81 M Bytes/second |
| 2016-06-18 09:35:25 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 10:16:45 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 10:16:45 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,709 bytes ... |
| 2016-06-18 10:19:46 | bacula-sd JobId 23428: Despooling elapsed time = 00:03:01, Transfer rate = 55.24 M Bytes/second |
| 2016-06-18 10:19:47 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 11:02:43 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 11:02:43 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,619 bytes ... |
| 2016-06-18 11:06:00 | bacula-sd JobId 23428: Despooling elapsed time = 00:03:17, Transfer rate = 50.76 M Bytes/second |
| 2016-06-18 11:06:00 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 11:48:01 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 11:48:01 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,691 bytes ... |
| 2016-06-18 11:51:11 | bacula-sd JobId 23428: Despooling elapsed time = 00:03:10, Transfer rate = 52.63 M Bytes/second |
| 2016-06-18 11:51:11 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 12:32:10 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 12:32:10 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,674 bytes ... |
| 2016-06-18 12:34:59 | bacula-sd JobId 23428: Despooling elapsed time = 00:02:49, Transfer rate = 59.17 M Bytes/second |
| 2016-06-18 12:35:00 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 13:18:52 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 13:18:52 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,637 bytes ... |
| 2016-06-18 13:22:05 | bacula-sd JobId 23428: Despooling elapsed time = 00:03:13, Transfer rate = 51.81 M Bytes/second |
| 2016-06-18 13:22:06 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 14:06:02 | bacula-sd JobId 23428: User specified spool size reached. |
| 2016-06-18 14:06:02 | bacula-sd JobId 23428: Writing spooled data to Volume. Despooling 10,000,202,568 bytes ... |
| 2016-06-18 14:08:53 | bacula-sd JobId 23428: Despooling elapsed time = 00:02:51, Transfer rate = 58.48 M Bytes/second |
| 2016-06-18 14:08:54 | bacula-sd JobId 23428: Spooling data again ... |
| 2016-06-18 14:36:43 | bacula-sd JobId 23428: Job write elapsed time = 14:48:19, Transfer rate = 3.703 M Bytes/second |
| 2016-06-18 14:36:43 | bacula-sd JobId 23428: Committing spooled data to Volume "L30017L3". Despooling 7,519,531,353 bytes ... |
| 2016-06-18 14:39:05 | bacula-sd JobId 23428: Despooling elapsed time = 00:02:22, Transfer rate = 52.95 M Bytes/second |
| 2016-06-18 14:39:06 | bacula-sd JobId 23428: Sending spooled attrs to the Director. Despooling 34,327,856 bytes ... |
| 2016-06-18 14:39:35 | bacula-dir JobId 23428: Bacula bacula-dir 5.2.6 (21Feb12): Build OS: x86_64-pc-linux-gnu ubuntu 12.10 JobId: 23428 Job: BackMonitor.2016-06-17_23.05.00_30 Backup Level: Full (upgraded from Differential) Client: "monitor-fd" 5.2.6 (21Feb12) x86_64-pc-linux-gnu,debian,7.0 FileSet: "FullServices" 2013-08-09 09:40:30 Pool: "Full" (From Job FullPool override) Catalog: "MyCatalog" (From Client resource) Storage: "TSM3500-LTO3" (From Job resource) Scheduled time: 17-Jun-2016 23:05:00 Start time: 17-Jun-2016 23:47:24 End time: 18-Jun-2016 14:39:35 Elapsed time: 14 hours 52 mins 11 secs Priority: 10 FD Files Written: 163,816 SD Files Written: 163,816 FD Bytes Written: 197,345,845,165 (197.3 GB) SD Bytes Written: 197,366,248,661 (197.3 GB) Rate: 3686.6 KB/s Software Compression: 68.1 % VSS: no Encryption: no Accurate: no Volume name(s): L30007L3|L30017L3 Volume Session Id: 83 Volume Session Time: 1465899516 Last Volume Bytes: 136,359,819,264 (136.3 GB) Non-fatal FD errors: 0 SD Errors: 0 FD termination status: OK SD termination status: OK Termination: Backup OK |
| 2016-06-18 14:39:35 | bacula-dir JobId 23428: Begin pruning Jobs older than 1 month 15 days . |
| 2016-06-18 14:39:35 | bacula-dir JobId 23428: No Jobs found to prune. |
| 2016-06-18 14:39:35 | bacula-dir JobId 23428: Begin pruning Files. |
| 2016-06-18 14:39:35 | bacula-dir JobId 23428: No Files found to prune. |
| 2016-06-18 14:39:35 | bacula-dir JobId 23428: End auto prune. |