Hello

I was making some tests with Verify Jobs, level=Data, and is not the first
time that I've noticed that the same Job got stuck (and is not so big).
The real size of this Job is near 51.95 GB (without compression) and 33.06
GB (with compression)


===============================================================================================================
The Verify Job
===============================================================================================================
Job{
  Name = "Verify_Data"
  Type = Verify
  Level = Data
  JobDefs = "Backup_Padrao"
  Pool = "Diaria"
  Enabled = no
}



===============================================================================================================
The Job that is being verified
===============================================================================================================
*list joblog jobid=15300
Automatically selected Catalog: MyCatalog
Using Catalog "MyCatalog"
+----------------------------------------------------------------------------------------------------+
| logtext
                            |
+----------------------------------------------------------------------------------------------------+
| bacula-dir JobId 15300: Start Backup JobId 15300,
Job=Backup_Servidor_Windows.2018-11-30_19.30.00_16    |
| bacula-dir JobId 15300: Using Device "DiscoLocal1" to write.
                           |
| bacula-sd JobId 15300: Volume "Volume-Semanal-0014" previously written,
moving to end of data.       |
| srv_windows-fd JobId 15300: Generate VSS snapshots. Driver="Win64 VSS"
                              |
| srv_windows-fd JobId 15300:     Snapshot mount point: J:\
                               |
| srv_windows-fd JobId 15300:     Snapshot mount point: K:\
                               |
| srv_windows-fd JobId 15300:     Snapshot mount point: C:\
                               |
| bacula-sd JobId 15300: End of medium on Volume "Volume-Semanal-0016"
Bytes=3,999,992,450 Blocks=62,005 at 30-Nov-2018 19:53. |
| bacula-dir JobId 15300: Purging oldest volume "Volume-Semanal-0003"
                            |
| bacula-dir JobId 15300: 5 Jobs on Volume "Volume-Semanal-0003" purged
from catalog.                  |
| bacula-dir JobId 15300: There are no more Jobs associated with Volume
"Volume-Semanal-0003". Marking it purged. |
| bacula-dir JobId 15300: All records pruned from Volume
"Volume-Semanal-0003"; marking it "Purged"    |
| bacula-sd JobId 15300: Recycled volume "Volume-Semanal-0003" on File
device "DiscoLocal1" (/mnt/disco01), all previous data lost. |
| bacula-sd JobId 15300: New volume "Volume-Semanal-0003" mounted on device
"DiscoLocal1" (/mnt/disco01) at 30-Nov-2018 19:53. |
| bacula-sd JobId 15300: End of medium on Volume "Volume-Semanal-0003"
Bytes=3,999,937,615 Blocks=62,003 at 30-Nov-2018 20:13. |
| bacula-dir JobId 15300: There are no more Jobs associated with Volume
"Volume-Semanal-0022". Marking it purged. |
| bacula-dir JobId 15300: All records pruned from Volume
"Volume-Semanal-0022"; marking it "Purged"    |
| bacula-dir JobId 15300: Recycled volume "Volume-Semanal-0022"
                            |
| bacula-sd JobId 15300: Recycled volume "Volume-Semanal-0022" on File
device "DiscoLocal1" (/mnt/disco01), all previous data lost. |
| bacula-sd JobId 15300: New volume "Volume-Semanal-0022" mounted on device
"DiscoLocal1" (/mnt/disco01) at 30-Nov-2018 20:13. |
| bacula-sd JobId 15300: End of medium on Volume "Volume-Semanal-0022"
Bytes=3,999,937,575 Blocks=62,003 at 30-Nov-2018 20:36. |
| bacula-dir JobId 15300: There are no more Jobs associated with Volume
"Volume-Semanal-0005". Marking it purged. |
| bacula-dir JobId 15300: All records pruned from Volume
"Volume-Semanal-0005"; marking it "Purged"    |
| bacula-dir JobId 15300: Recycled volume "Volume-Semanal-0005"
                            |
| bacula-sd JobId 15300: Recycled volume "Volume-Semanal-0005" on File
device "DiscoLocal1" (/mnt/disco01), all previous data lost. |
| bacula-sd JobId 15300: New volume "Volume-Semanal-0005" mounted on device
"DiscoLocal1" (/mnt/disco01) at 30-Nov-2018 20:36. |
| bacula-sd JobId 15300: End of medium on Volume "Volume-Semanal-0005"
Bytes=3,999,937,548 Blocks=62,003 at 30-Nov-2018 20:51. |
| bacula-dir JobId 15300: There are no more Jobs associated with Volume
"Volume-Semanal-0018". Marking it purged. |
| bacula-dir JobId 15300: All records pruned from Volume
"Volume-Semanal-0018"; marking it "Purged"    |
| bacula-dir JobId 15300: Recycled volume "Volume-Semanal-0018"
                            |
| bacula-sd JobId 15300: Recycled volume "Volume-Semanal-0018" on File
device "DiscoLocal1" (/mnt/disco01), all previous data lost. |
| bacula-sd JobId 15300: New volume "Volume-Semanal-0018" mounted on device
"DiscoLocal1" (/mnt/disco01) at 30-Nov-2018 20:51. |
| bacula-sd JobId 15300: End of medium on Volume "Volume-Semanal-0018"
Bytes=3,999,937,670 Blocks=62,003 at 30-Nov-2018 21:05. |
| bacula-dir JobId 15300: There are no more Jobs associated with Volume
"Volume-Semanal-0024". Marking it purged. |
| bacula-dir JobId 15300: All records pruned from Volume
"Volume-Semanal-0024"; marking it "Purged"    |
| bacula-dir JobId 15300: Recycled volume "Volume-Semanal-0024"
                            |
| bacula-sd JobId 15300: Recycled volume "Volume-Semanal-0024" on File
device "DiscoLocal1" (/mnt/disco01), all previous data lost. |
| bacula-sd JobId 15300: New volume "Volume-Semanal-0024" mounted on device
"DiscoLocal1" (/mnt/disco01) at 30-Nov-2018 21:05. |
| bacula-sd JobId 15300: End of medium on Volume "Volume-Semanal-0024"
Bytes=3,999,937,530 Blocks=62,003 at 30-Nov-2018 21:29. |
| bacula-dir JobId 15300: There are no more Jobs associated with Volume
"Volume-Semanal-0012". Marking it purged. |
| bacula-dir JobId 15300: All records pruned from Volume
"Volume-Semanal-0012"; marking it "Purged"    |
| bacula-dir JobId 15300: Recycled volume "Volume-Semanal-0012"
                            |
| bacula-sd JobId 15300: Recycled volume "Volume-Semanal-0012" on File
device "DiscoLocal1" (/mnt/disco01), all previous data lost. |
| bacula-sd JobId 15300: New volume "Volume-Semanal-0012" mounted on device
"DiscoLocal1" (/mnt/disco01) at 30-Nov-2018 21:29. |
| bacula-sd JobId 15300: End of medium on Volume "Volume-Semanal-0012"
Bytes=3,999,937,567 Blocks=62,003 at 30-Nov-2018 21:51. |
| bacula-dir JobId 15300: There are no more Jobs associated with Volume
"Volume-Semanal-0008". Marking it purged. |
| bacula-dir JobId 15300: All records pruned from Volume
"Volume-Semanal-0008"; marking it "Purged"    |
| bacula-dir JobId 15300: Recycled volume "Volume-Semanal-0008"
                            |
| bacula-sd JobId 15300: Recycled volume "Volume-Semanal-0008" on File
device "DiscoLocal1" (/mnt/disco01), all previous data lost. |
| bacula-sd JobId 15300: New volume "Volume-Semanal-0008" mounted on device
"DiscoLocal1" (/mnt/disco01) at 30-Nov-2018 21:51. |
| bacula-sd JobId 15300: End of medium on Volume "Volume-Semanal-0008"
Bytes=3,999,937,644 Blocks=62,003 at 30-Nov-2018 22:06. |
| bacula-dir JobId 15300: There are no more Jobs associated with Volume
"Volume-Semanal-0001". Marking it purged. |
| bacula-dir JobId 15300: All records pruned from Volume
"Volume-Semanal-0001"; marking it "Purged"    |
| bacula-dir JobId 15300: Recycled volume "Volume-Semanal-0001"
                            |
| bacula-sd JobId 15300: Recycled volume "Volume-Semanal-0001" on File
device "DiscoLocal1" (/mnt/disco01), all previous data lost. |
| bacula-sd JobId 15300: New volume "Volume-Semanal-0001" mounted on device
"DiscoLocal1" (/mnt/disco01) at 30-Nov-2018 22:06. |
| bacula-sd JobId 15300: End of medium on Volume "Volume-Semanal-0001"
Bytes=3,999,937,619 Blocks=62,003 at 30-Nov-2018 22:17. |
| bacula-dir JobId 15300: There are no more Jobs associated with Volume
"Volume-Semanal-0013". Marking it purged. |
| bacula-dir JobId 15300: All records pruned from Volume
"Volume-Semanal-0013"; marking it "Purged"    |
| bacula-dir JobId 15300: Recycled volume "Volume-Semanal-0013"
                            |
| bacula-sd JobId 15300: Recycled volume "Volume-Semanal-0013" on File
device "DiscoLocal1" (/mnt/disco01), all previous data lost. |
| bacula-sd JobId 15300: New volume "Volume-Semanal-0013" mounted on device
"DiscoLocal1" (/mnt/disco01) at 30-Nov-2018 22:17. |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "Task Scheduler
Writer", State: 0x1 (VSS_WS_STABLE) |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "VSS Metadata
Store Writer", State: 0x1 (VSS_WS_STABLE) |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "Performance
Counters Writer", State: 0x1 (VSS_WS_STABLE) |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "System Writer",
State: 0x1 (VSS_WS_STABLE)    |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "FSRM Writer",
State: 0x1 (VSS_WS_STABLE)      |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "ASR Writer",
State: 0x1 (VSS_WS_STABLE)       |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "BITS Writer",
State: 0x1 (VSS_WS_STABLE)      |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "WMI Writer",
State: 0x1 (VSS_WS_STABLE)       |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "IIS Metabase
Writer", State: 0x1 (VSS_WS_STABLE) |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "WIDWriter",
State: 0x1 (VSS_WS_STABLE)        |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "Shadow Copy
Optimization Writer", State: 0x1 (VSS_WS_STABLE) |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "IIS Config
Writer", State: 0x1 (VSS_WS_STABLE) |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "Dedup Writer",
State: 0x1 (VSS_WS_STABLE)     |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete):
"TermServLicensing", State: 0x1 (VSS_WS_STABLE) |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "Registry
Writer", State: 0x1 (VSS_WS_STABLE)  |
| srv_windows-fd JobId 15300: VSS Writer (BackupComplete): "COM+ REGDB
Writer", State: 0x1 (VSS_WS_STABLE) |
| bacula-sd JobId 15300: Elapsed time=02:50:28, Transfer rate=3.478 M
Bytes/second                     |
| bacula-sd JobId 15300: Sending spooled attrs to the Director. Despooling
113,290,334 bytes ...       |
| bacula-dir JobId 15300: Bacula bacula-dir 9.2.2 (06Nov18):
  Build OS:               x86_64-pc-linux-gnu debian 9.5
  JobId:                  15300
  Job:                    Backup_Servidor_Windows.2018-11-30_19.30.00_16
  Backup Level:           Full
  Client:                 "srv_windows-fd" 9.2.2 (06Nov18) Microsoft
Standard Edition (build 9200), 64-bit,Cross-compile,Win64
  FileSet:                "FileSet_Windows" 2016-09-30 19:30:00
  Pool:                   "Semanal" (From Run Pool override)
  Catalog:                "MyCatalog" (From Client resource)
  Storage:                "StorageLocal1" (From Pool resource)
  Scheduled time:         30-Nov-2018 19:30:00
  Start time:             30-Nov-2018 19:30:01
  End time:               30-Nov-2018 22:21:47
  Elapsed time:           2 hours 51 mins 46 secs
  Priority:               10
  FD Files Written:       289,814
  SD Files Written:       289,814
  FD Bytes Written:       35,493,430,412 (35.49 GB)
  SD Bytes Written:       35,575,656,410 (35.57 GB)
  Rate:                   3444.0 KB/s
  Software Compression:   36.3% 1.6:1
  Comm Line Compression:  3.6% 1.0:1
  Snapshot/VSS:           yes
  Encryption:             no
  Accurate:               no
  Volume name(s):
 
Volume-Semanal-0014|Volume-Semanal-0016|Volume-Semanal-0003|Volume-Semanal-0022|Volume-Semanal-0005|Volume-Semanal-0018|Volume-Semanal-0024|Volume-Semanal-0012|Volume-Semanal-0008|Volume-Semanal-0001|Volume-Semanal-0013
  Volume Session Id:      175
  Volume Session Time:    1542619240
  Last Volume Bytes:      1,077,160,260 (1.077 GB)
  Non-fatal FD errors:    0
  SD Errors:              0
  FD termination status:  OK
  SD termination status:  OK
  Termination:            Backup OK |
| bacula-dir JobId 15300: Begin pruning Jobs older than 1 year .
                           |
| bacula-dir JobId 15300: No Jobs found to prune.
                            |
| bacula-dir JobId 15300: Begin pruning Files.
                           |
| bacula-dir JobId 15300: No Files found to prune.
                           |
| bacula-dir JobId 15300: End auto prune.
                            |
| bacula-dir JobId 15300: shell command: run AfterJob
"/etc/bacula/scripts/_send_telegram.sh 15300"    |
| bacula-dir JobId 15300: shell command: run AfterJob
"/etc/bacula/scripts/_webacula_update_filesize.sh 15300 Backup OK" |
| bacula-dir JobId 15300: AfterJob: The JobSize and FileSize of JobId 15300
were updated successfully! |
+----------------------------------------------------------------------------------------------------+
+--------+----------------------+---------------------+------+-------+----------+----------------+-----------+
| jobid  | name                 | starttime           | type | level |
jobfiles | jobbytes       | jobstatus |
+--------+----------------------+---------------------+------+-------+----------+----------------+-----------+
| 15,300 | Backup_Servidor_Windows | 2018-11-30 19:30:01 | B    | F     |
289,814 | 35,493,430,412 | T         |
+--------+----------------------+---------------------+------+-------+----------+----------------+-----------+


===============================================================================================================
The command used to run Verify Job
===============================================================================================================
run job=Verify_Data  client=srv_windows-fd  fileset=FileSet_Windows
jobid=15300  storage=StorageLocal1  priority=10  yes



===============================================================================================================
Status Client
===============================================================================================================
The status client it looks is stopped in the same file more than 3 hours
and the file only has 64kb


srv_windows-fd Version: 9.2.2 (06 November 2018)  VSS Linux Cross-compile
Win64
Daemon started 01-Dec-18 01:11. Jobs: run=1 running=0.
Microsoft Standard Edition (build 9200), 64-bit
Priv 0x73f
Memory: WorkingSetSize: 2,490,368 QuotaPagedPoolUsage: 169,672
QuotaNonPagedPoolUsage: 13,328 PagefileUsage: 3,301,376
APIs=OPT,ATP,LPV,CFA,CFW,
 WUL,WMKD,GFAA,GFAW,GFAEA,GFAEW,SFAA,SFAW,BR,BW,SPSP,
 WC2MB,MB2WC,FFFA,FFFW,FNFA,FNFW,SCDA,SCDW,
 GCDA,GCDW,GVPNW,GVNFVMPW,LZO,!EFS
 Heap: heap=2,490,368 smbytes=883,020 max_bytes=1,074,917 bufs=134
max_bufs=157
 Sizes: boffset_t=8 size_t=8 debug=500 trace=1 mode=0,0 bwlimit=0kB/s

Running Jobs:
JobId 15317 Job Verify_Data.2018-12-03_08.54.21_01 is running.
    Verify Data Verify Job started: 03-Dec-18 08:54
    Files=52,411 Bytes=2,863,295,550 AveBytes/sec=129,379 LastBytes/sec=38
Errors=0
    Bwlimit=0 ReadBytes=2,127,084,878
    Files: Examined=52,411 Backed up=52,411
    Processing file: k:/empresas/Empresas Ativas/Nome Empresa 1/Recursos
Humanos/Extração SEFIP/Resumo/resumo 082018.pdf
    SDReadSeqNo=376462 fd=948 SDtls=0
Director connected at: 03-Dec-18 15:03
====

Terminated Jobs:
 JobId  Level    Files      Bytes   Status   Finished        Name
===================================================================
 15265  Incr          3    1.945 G  OK       28-Nov-18 22:07
Backup_Questor_FDB
 15273  Full          3    841.8 M  OK       29-Nov-18 12:18
Backup_Questor_FBK
 15277  Full          3    842.1 M  OK       29-Nov-18 21:41
Backup_Questor_FBK
 15278  Incr        149    45.56 M  OK       29-Nov-18 22:10
Backup_Servidor_Windows
 15282  Incr          3    1.962 G  OK       29-Nov-18 22:18
Backup_Questor_FDB
 15295  Full          3    842.3 M  OK       30-Nov-18 12:18
Backup_Questor_FBK
 15299  Full          3    842.6 M  OK       30-Nov-18 19:43
Backup_Questor_FBK
 15300  Full    289,814    35.49 G  OK       30-Nov-18 22:21
Backup_Servidor_Windows
 15304  Full          3    1.970 G  OK       30-Nov-18 22:30
Backup_Questor_FDB
 15313  Veri          3    8.666 G  OK       03-Dec-18 08:34 Verify_Data
====



===============================================================================================================
List files from server
===============================================================================================================
*.ls client=srv_windows-fd path="K:/empresas/Empresas Ativas/Nome Empresa
1/Recursos Humanos/Extração SEFIP/Resumo"
Connecting to Client srv_windows-fd at srv_windows.famacris.local:9102
-rwxrwxrwx   1 0        0              84499 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 012017.pdf
-rwxrwxrwx   1 0        0              64013 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 012018.pdf
-rwxrwxrwx   1 0        0              77093 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 022017.pdf
-rwxrwxrwx   1 0        0              65343 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 022018.pdf
-rwxrwxrwx   1 0        0              81744 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 032017.pdf
-rwxrwxrwx   1 0        0              63649 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 032018.pdf
-rwxrwxrwx   1 0        0              84190 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 042017.pdf
-rwxrwxrwx   1 0        0              67441 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 042018.pdf
-rwxrwxrwx   1 0        0              85258 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/Resumo 052017.pdf
-rwxrwxrwx   1 0        0              64788 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 052018.pdf
-rwxrwxrwx   1 0        0              86700 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 062017.pdf
-rwxrwxrwx   1 0        0              64048 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 062018.pdf
-rwxrwxrwx   1 0        0              81549 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 072017.pdf
-rwxrwxrwx   1 0        0              66492 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 072018.pdf
-rwxrwxrwx   1 0        0              84770 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 082017.pdf
-rwxrwxrwx   1 0        0              65099 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 082018.pdf
-rwxrwxrwx   1 0        0              86741 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 092017.pdf
-rwxrwxrwx   1 0        0              85824 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 102017.pdf
-rwxrwxrwx   1 0        0              89264 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 112017.pdf
-rwxrwxrwx   1 0        0              90247 2018-10-05 13:53:06
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo/resumo 122017.pdf
drwxrwxrwx   1 0        0                  0 2018-10-05 14:09:07
K:/empresas/Empresas Ativas/Nome Empresa 1/Recursos Humanos/Extração
SEFIP/Resumo
2000 OK estimate files=21 bytes=1,538,752



Best regards

*Wanderlei Hüttel*
http://www.bacula.com.br
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users

Reply via email to