Bacula-users

[Bacula-users] ODP: waiting for appendable volume - why?

2009-07-16 05:06:13
Subject: [Bacula-users] ODP: waiting for appendable volume - why?
From: "Lukasz PUZON Brodowski" <puzon AT eska DOT pl>
To: "'John Drescher'" <drescherjm AT gmail DOT com>
Date: Thu, 16 Jul 2009 10:59:43 +0200

> -----Wiadomość oryginalna-----
> Od: John Drescher [mailto:drescherjm AT gmail DOT com]
> Wysłano: 16 lipca 2009 10:39
> Do: Lukasz PUZON Brodowski
> DW: bacula-users AT lists.sourceforge DOT net
> Temat: Re: [Bacula-users] waiting for appendable volume - why?
> 
> On Thu, Jul 16, 2009 at 3:39 AM, Lukasz PUZON Brodowski<puzon AT eska DOT pl>
> wrote:
> > Hi everybody. I have problem with jobs that stop and "waiting for
> appendable
> > volume". Eg. 2 jobs fail, because of network problems, other jobs are
> in
> > running state, exclude one that waiting for appendable volume. And
> all jobs
> > are waiting. Why? I use autolabel volumes (files), and for most time
> > everything works fine. When this problem appears, I must use label
> command,
> > and create new label - then everything run fine.
> >
> Without any details / log output it is impossible to help.
> 

Ok - here is log:
<cut>
16-Jul 03:33 backup-dir JobId 146: Rescheduled Job
Backup_serwer-news.2009-07-15_23.30.01_48 at 16-Jul-2009 03:33 to re-run in
1800 seconds (16-Jul-2009 04:03).
16-Jul 03:33 backup-dir JobId 146: Job
Backup_serwer-news.2009-07-15_23.30.01_48 waiting 1800 seconds for scheduled
start time.
16-Jul 03:33 backup-dir JobId 145: Fatal error: No Job status returned from
FD.
16-Jul 03:33 backup-dir JobId 145: Error: Bacula backup-dir 3.0.0 (06Apr09):
16-Jul-2009 03:33:54
  Build OS:               i386-portbld-freebsd7.0 freebsd 7.0-RELEASE-p10
  JobId:                  145
  Job:                    Backup_serwer-www.2009-07-15_23.30.01_47
  Backup Level:           Incremental, since=2009-07-14 23:30:02
  Client:                 "serwer-www-fd" 2.4.1 (07Jul08)
i386-portbld-freebsd6.2,freebsd,6.2-RELEASE
  FileSet:                "KatalogiNaSerwer-www" 2009-07-01 23:30:01
  Pool:                   "Inc-pool" (From Job IncPool override)
  Catalog:                "MySQL" (From Client resource)
  Storage:                "File_dane_s2" (From Job resource)
  Scheduled time:         15-Jul-2009 23:30:01
  Start time:             16-Jul-2009 03:33:37
  End time:               16-Jul-2009 03:33:54
  Elapsed time:           17 secs
  Priority:               10
  FD Files Written:       0
  SD Files Written:       0
  FD Bytes Written:       0 (0 B)
  SD Bytes Written:       0 (0 B)
  Rate:                   0.0 KB/s
  Software Compression:   None
  VSS:                    no
  Encryption:             no
  Accurate:               no
  Volume name(s):         
  Volume Session Id:      55
  Volume Session Time:    1247462148
  Last Volume Bytes:      0 (0 B)
  Non-fatal FD errors:    4
  SD Errors:              0
  FD termination status:  Error
  SD termination status:  Error
  Termination:            *** Backup Error ***

16-Jul 03:33 backup-dir JobId 145: Rescheduled Job
Backup_serwer-www.2009-07-15_23.30.01_47 at 16-Jul-2009 03:33 to re-run in
1800 seconds (16-Jul-2009 04:03).
16-Jul 03:33 backup-dir JobId 145: Job
Backup_serwer-www.2009-07-15_23.30.01_47 waiting 1800 seconds for scheduled
start time.
16-Jul 04:03 backup-dir JobId 146: Start Backup JobId 146,
Job=Backup_serwer-news.2009-07-15_23.30.01_48
16-Jul 04:03 backup-dir JobId 146: Using Device "FileStorage_dane_s2"
16-Jul 04:03 serwer-news-fd JobId 146: DIR and FD clocks differ by -28
seconds, FD automatically compensating.
16-Jul 04:03 backup-dir JobId 145: Start Backup JobId 145,
Job=Backup_serwer-www.2009-07-15_23.30.01_47
16-Jul 04:03 backup-dir JobId 145: Using Device "FileStorage_dane_s2"
16-Jul 04:03 serwer-www-fd JobId 145: DIR and FD clocks differ by -28
seconds, FD automatically compensating.
16-Jul 04:03 serwer-news-fd JobId 146: Fatal error: Authorization key
rejected by Storage daemon.
Please see http://www.bacula.org/rel-manual/faq.html#AuthorizationErrors for
help.
16-Jul 04:03 serwer-www-fd JobId 145: Fatal error: Authorization key
rejected by Storage daemon.
Please see http://www.bacula.org/rel-manual/faq.html#AuthorizationErrors for
help.
16-Jul 04:03 serwer-news-fd JobId 146: Fatal error: Failed to authenticate
Storage daemon.
16-Jul 04:04 backup-dir JobId 146: Fatal error: Socket error on Storage
command: ERR=Broken pipe
16-Jul 04:03 serwer-www-fd JobId 145: Fatal error: Failed to authenticate
Storage daemon.
16-Jul 04:04 backup-dir JobId 145: Fatal error: Socket error on Storage
command: ERR=Broken pipe
16-Jul 04:04 backup-dir JobId 146: Fatal error: No Job status returned from
FD.
16-Jul 04:04 backup-dir JobId 146: Error: Bacula backup-dir 3.0.0 (06Apr09):
16-Jul-2009 04:04:09
  Build OS:               i386-portbld-freebsd7.0 freebsd 7.0-RELEASE-p10
  JobId:                  146
  Job:                    Backup_serwer-news.2009-07-15_23.30.01_48
  Backup Level:           Incremental, since=2009-07-14 23:30:05
  Client:                 "serwer-news-fd" 2.4.1 (07Jul08)
Linux,Cross-compile,Win32
  FileSet:                "KatalogiNaSerwer-news" 2009-07-01 23:30:01
  Pool:                   "Inc-pool" (From Job IncPool override)
  Catalog:                "MySQL" (From Client resource)
  Storage:                "File_dane_s2" (From Job resource)
  Scheduled time:         15-Jul-2009 23:30:01
  Start time:             16-Jul-2009 04:03:52
  End time:               16-Jul-2009 04:04:09
  Elapsed time:           17 secs
  Priority:               10
  FD Files Written:       0
  SD Files Written:       0
  FD Bytes Written:       0 (0 B)
  SD Bytes Written:       0 (0 B)
  Rate:                   0.0 KB/s
  Software Compression:   None
  VSS:                    no
  Encryption:             no
  Accurate:               no
  Volume name(s):         
  Volume Session Id:      56
  Volume Session Time:    1247462148
  Last Volume Bytes:      0 (0 B)
  Non-fatal FD errors:    5
  SD Errors:              0
  FD termination status:  Error
  SD termination status:  Error
  Termination:            *** Backup Error ***

16-Jul 04:04 backup-dir JobId 145: Fatal error: No Job status returned from
FD.
16-Jul 04:04 backup-dir JobId 145: Error: Bacula backup-dir 3.0.0 (06Apr09):
16-Jul-2009 04:04:13
  Build OS:               i386-portbld-freebsd7.0 freebsd 7.0-RELEASE-p10
  JobId:                  145
  Job:                    Backup_serwer-www.2009-07-15_23.30.01_47
  Backup Level:           Incremental, since=2009-07-14 23:30:02
  Client:                 "serwer-www-fd" 2.4.1 (07Jul08)
i386-portbld-freebsd6.2,freebsd,6.2-RELEASE
  FileSet:                "KatalogiNaSerwer-www" 2009-07-01 23:30:01
  Pool:                   "Inc-pool" (From Job IncPool override)
  Catalog:                "MySQL" (From Client resource)
  Storage:                "File_dane_s2" (From Job resource)
  Scheduled time:         15-Jul-2009 23:30:01
  Start time:             16-Jul-2009 04:03:56
  End time:               16-Jul-2009 04:04:13
  Elapsed time:           17 secs
  Priority:               10
  FD Files Written:       0
  SD Files Written:       0
  FD Bytes Written:       0 (0 B)
  SD Bytes Written:       0 (0 B)
  Rate:                   0.0 KB/s
  Software Compression:   None
  VSS:                    no
  Encryption:             no
  Accurate:               no
  Volume name(s):         
  Volume Session Id:      57
  Volume Session Time:    1247462148
  Last Volume Bytes:      0 (0 B)
  Non-fatal FD errors:    5
  SD Errors:              0
  FD termination status:  Error
  SD termination status:  Error
  Termination:            *** Backup Error ***

16-Jul 06:31 backup.eska-sd JobId 149: Job
Backup_pliki.warszawa.eska.2009-07-15_23.30.01_51 waiting. Cannot find any
appendable volumes.
Please use the "label"  command to create a new Volume for:
    Storage:      "FileStorage_dane_s2" (/dane_s2)
    Pool:         Inc-pool
    Media type:   File
</cut>


Status dir:

<cut>
Running Jobs:
Console connected at 16-Jul-09 08:20
 JobId Level   Name                       Status
======================================================================
   147 Increme  Backup_badania.2009-07-15_23.30.01_49 is running
   148 Increme  Backup_mssql.2009-07-15_23.30.01_50 is running
   149 Increme  Backup_pliki.warszawa.eska.2009-07-15_23.30.01_51 is waiting
for an appendable Volume
   150 Increme  Backup_pgsql.eska.fm.2009-07-15_23.30.01_52 is running
   151 Increme  Backup_analizy.2009-07-15_23.30.01_53 is running
   152 Increme  Backup_localhost.2009-07-15_23.30.01_54 is running
   153 Increme  Backup_ts01.2009-07-15_23.30.01_55 is running
   154 Increme  Backup_gsel_poz.2009-07-15_23.30.01_56 is running
   155 Increme  Backup_gsel_wroc.2009-07-15_23.30.01_57 is running
====
</cut>


<cut>
Status sd:
#status sd
The defined Storage resources are:
     1: File_dane1
     2: File_dane2
     3: File_dane_s1
     4: File_dane_s2
Select Storage resource (1-4): Unexpected question has been received.
4
Connecting to Storage daemon File_dane_s2 at X.X.X.X:9103

backup.eska-sd Version: 3.0.0 (06 April 2009) i386-portbld-freebsd7.0
freebsd 7.0-RELEASE-p10
Daemon started 13-Jul-09 07:15, 46 Jobs run since started.
 Heap: heap=0 smbytes=2,594,604 max_bytes=2,863,746 bufs=474 max_bufs=544
Sizes: boffset_t=8 size_t=4 int32_t=4 int64_t=8

Running Jobs:
Writing: Incremental Backup job Backup_serwer-www JobId=145 Volume=""
    pool="Inc-pool" device="FileStorage_dane_s2" (/dane_s2)
    spooling=0 despooling=0 despool_wait=0
    Files=0 Bytes=0 Bytes/sec=0
    FDReadSeqNo=6 in_msg=6 out_msg=6 fd=20
Writing: Incremental Backup job Backup_serwer-news JobId=146 Volume=""
    pool="Inc-pool" device="FileStorage_dane_s2" (/dane_s2)
    spooling=0 despooling=0 despool_wait=0
    Files=0 Bytes=0 Bytes/sec=0
    FDReadSeqNo=6 in_msg=6 out_msg=6 fd=21
Writing: Incremental Backup job Backup_badania JobId=147 Volume=""
    pool="Inc-pool" device="FileStorage_dane_s2" (/dane_s2)
    spooling=0 despooling=0 despool_wait=0
    Files=0 Bytes=0 Bytes/sec=0
    FDReadSeqNo=6 in_msg=6 out_msg=4 fd=18
Writing: Incremental Backup job Backup_pliki.warszawa.eska JobId=149
Volume=""
    pool="Inc-pool" device="FileStorage_dane_s2" (/dane_s2)
    spooling=0 despooling=0 despool_wait=0
    Files=0 Bytes=0 Bytes/sec=0
    FDReadSeqNo=6 in_msg=6 out_msg=1050 fd=15
Writing: Incremental Backup job Backup_mssql JobId=148 Volume=""
    pool="Inc-pool" device="FileStorage_dane_s2" (/dane_s2)
    spooling=0 despooling=0 despool_wait=0
    Files=0 Bytes=0 Bytes/sec=0
    FDReadSeqNo=6 in_msg=6 out_msg=4 fd=19
Writing: Incremental Backup job Backup_pgsql.eska.fm JobId=150 Volume=""
    pool="Inc-pool" device="FileStorage_dane_s2" (/dane_s2)
    spooling=0 despooling=0 despool_wait=0
    Files=0 Bytes=0 Bytes/sec=0
    FDReadSeqNo=6 in_msg=6 out_msg=4 fd=16
Writing: Incremental Backup job Backup_analizy JobId=151 Volume=""
    pool="Inc-pool" device="FileStorage_dane_s2" (/dane_s2)
    spooling=0 despooling=0 despool_wait=0
    Files=0 Bytes=0 Bytes/sec=0
    FDReadSeqNo=6 in_msg=6 out_msg=4 fd=22
Writing: Incremental Backup job Backup_localhost JobId=152 Volume=""
    pool="Inc-pool" device="FileStorage_dane_s2" (/dane_s2)
    spooling=0 despooling=0 despool_wait=0
    Files=0 Bytes=0 Bytes/sec=0
    FDReadSeqNo=6 in_msg=6 out_msg=4 fd=17
Writing: Incremental Backup job Backup_ts01 JobId=153 Volume=""
    pool="Inc-pool" device="FileStorage_dane_s2" (/dane_s2)
    spooling=0 despooling=0 despool_wait=0
    Files=0 Bytes=0 Bytes/sec=0
    FDReadSeqNo=6 in_msg=6 out_msg=4 fd=23
Writing: Incremental Backup job Backup_gsel_poz JobId=154 Volume=""
    pool="Inc-pool" device="FileStorage_dane_s2" (/dane_s2)
    spooling=0 despooling=0 despool_wait=0
    Files=0 Bytes=0 Bytes/sec=0
    FDReadSeqNo=6 in_msg=6 out_msg=4 fd=27
Writing: Incremental Backup job Backup_gsel_wroc JobId=155 Volume=""
    pool="Inc-pool" device="FileStorage_dane_s2" (/dane_s2)
    spooling=0 despooling=0 despool_wait=0
    Files=0 Bytes=0 Bytes/sec=0
    FDReadSeqNo=6 in_msg=6 out_msg=4 fd=26
====

Jobs waiting to reserve a drive:
====

Terminated Jobs:
 JobId  Level    Files      Bytes   Status   Finished        Name 
===================================================================
   146  Incr          0         0   Other    16-Jul-09 02:33
Backup_serwer-news
   145  Incr          0         0   Other    16-Jul-09 02:33
Backup_serwer-www
   146  Incr          0         0   Other    16-Jul-09 03:03
Backup_serwer-news
   145  Incr          0         0   Other    16-Jul-09 03:03
Backup_serwer-www
   146  Incr          0         0   Other    16-Jul-09 03:34
Backup_serwer-news
   145  Incr          0         0   Other    16-Jul-09 03:34
Backup_serwer-www
   146  Incr          0         0   Other    16-Jul-09 04:04
Backup_serwer-news
   145  Incr          0         0   Other    16-Jul-09 04:04
Backup_serwer-www
   146  Incr          0         0   Other    16-Jul-09 04:34
Backup_serwer-news
   145  Incr          0         0   Other    16-Jul-09 04:34
Backup_serwer-www
====

Device status:
Device "FileStorage_dane1" (/dane1/bacula_backup_3) is not open.
Device "FileStorage_dane2" (/dane2/bacula_backup_3) is not open.
Device "FileStorage_dane_s1" (/dane_s1) is not open.
Device "FileStorage_dane_s2" (/dane_s2) is not open.
    Device is BLOCKED waiting to create a volume for:
       Pool:        Inc-pool
       Media type:  File
</cut>


Then I send: 
#label
Automatically selected Catalog: MySQL
Using Catalog "MySQL"
The defined Storage resources are:
     1: File_dane1
     2: File_dane2
     3: File_dane_s1
     4: File_dane_s2
Select Storage resource (1-4): Unexpected question has been received.
4
Enter new Volume name: Unexpected question has been received.
Inc-0010
Defined Pools:
     1: Full-pool
     2: Diff-pool
     3: Inc-pool
     4: Archive
     5: Default
Select the Pool (1-5): Unexpected question has been received.
3

And everything run and ending fine.



------------------------------------------------------------------------------
Enter the BlackBerry Developer Challenge  
This is your chance to win up to $100,000 in prizes! For a limited time, 
vendors submitting new applications to BlackBerry App World(TM) will have
the opportunity to enter the BlackBerry Developer Challenge. See full prize  
details at: http://p.sf.net/sfu/Challenge
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users