Bacula-users

Re: [Bacula-users] SDLT4 Problem

2008-06-03 11:58:48
Subject: Re: [Bacula-users] SDLT4 Problem
From: "John Drescher" <drescherjm AT gmail DOT com>
To: Steffen.Knauf AT renderforce DOT de
Date: Tue, 3 Jun 2008 11:58:18 -0400
> -------------------------------------------------------------------
> 02-Jun 18:22 c06-dir JobId 36: Start Backup JobId 36,
> Job=Library_MUC.2008-06-02_18.22.03
> 02-Jun 18:22 c06-dir JobId 36: Recycled current volume
> "Vol_full_lib_MUC0018"
> 02-Jun 18:22 c06-dir JobId 36: Using Device "SDLT_MUC"
> 02-Jun 18:22 mastermind-sd JobId 36: Recycled volume
> "Vol_full_lib_MUC0018" on device "SDLT_MUC" (/dev/st0), all previous
> data lost.
> 02-Jun 18:22 c06-dir JobId 36: Max Volume jobs exceeded. Marking Volume
> "Vol_full_lib_MUC0018" as Used.
> 03-Jun 14:35 mastermind-sd JobId 36: End of Volume
> "Vol_full_lib_MUC0018" at 1105:7277 on device "SDLT_MUC" (/dev/st0).
> Write of 64512 bytes got -1.
> 03-Jun 14:35 mastermind-sd JobId 36: Re-read of last block succeeded.
> 03-Jun 14:35 mastermind-sd JobId 36: End of medium on Volume
> "Vol_full_lib_MUC0018" Bytes=1,105,398,733,824 Blocks=17,134,776 at
> 03-Jun-2008 14:35.
> 03-Jun 14:36 mastermind-sd JobId 36: Please mount Volume
> "Vol_full_lib_MUC0027" or label a new one for:
>   Job:          Library_MUC.2008-06-02_18.22.03
>   Storage:      "SDLT_MUC" (/dev/st0)
>   Pool:         Full_Lib_MUC
>   Media type:   DLT-S4
> 03-Jun 15:36 mastermind-sd JobId 36: Please mount Volume
> "Vol_full_lib_MUC0027" or label a new one for:
>   Job:          Library_MUC.2008-06-02_18.22.03
>   Storage:      "SDLT_MUC" (/dev/st0)
>   Pool:         Full_Lib_MUC
>   Media type:   DLT-S4
>
No errors to this point.
>
> 03-Jun 15:48 mastermind-sd JobId 36: Error: block.c:995 Read error on
> fd=3 at file:blk 0:0 on device "SDLT_MUC" (/dev/st0). ERR=Input/output
> error.
>
This is normal. The tape read failed because the media was never written to.
>
> 03-Jun 15:48 mastermind-sd JobId 36: Please mount Volume
> "Vol_full_lib_MUC0027" or label a new one for:
>   Job:          Library_MUC.2008-06-02_18.22.03
>   Storage:      "SDLT_MUC" (/dev/st0)
>   Pool:         Full_Lib_MUC
>   Media type:   DLT-S4
> 03-Jun 15:49 mastermind-sd JobId 36: Error: block.c:995 Read error on
> fd=3 at file:blk 0:0 on device "SDLT_MUC" (/dev/st0). ERR=Input/output
> error.
> 03-Jun 15:49 mastermind-sd JobId 36: Please mount Volume
> "Vol_full_lib_MUC0027" or label a new one for:
>   Job:          Library_MUC.2008-06-02_18.22.03
>   Storage:      "SDLT_MUC" (/dev/st0)
>   Pool:         Full_Lib_MUC
>   Media type:   DLT-S4
> 03-Jun 15:55 mastermind-sd JobId 36: Error: block.c:995 Read error on
> fd=3 at file:blk 0:0 on device "SDLT_MUC" (/dev/st0). ERR=Input/output
> error.
>
You should only see the read error 1 time.
>
> 03-Jun 15:55 mastermind-sd JobId 36: Please mount Volume
> "Vol_full_lib_MUC0027" or label a new one for:
>   Job:          Library_MUC.2008-06-02_18.22.03
>   Storage:      "SDLT_MUC" (/dev/st0)
>   Pool:         Full_Lib_MUC
>   Media type:   DLT-S4
> 03-Jun 15:56 mastermind-sd JobId 36: Error: block.c:995 Read error on
> fd=3 at file:blk 0:0 on device "SDLT_MUC" (/dev/st0). ERR=Input/output
> error.
>
You should only see the read error 1 time.
>
> 03-Jun 15:56 c06-dir JobId 36: Created new Volume "Vol_full_lib_MUC0027"
> in catalog.
This should have already happened.
>
> 03-Jun 15:56 mastermind-sd JobId 36: Please mount Volume
> "Vol_full_lib_MUC0027" or label a new one for:
>   Job:          Library_MUC.2008-06-02_18.22.03
>   Storage:      "SDLT_MUC" (/dev/st0)
>   Pool:         Full_Lib_MUC
>   Media type:   DLT-S4
> 03-Jun 15:58 mastermind-sd JobId 36: Error: block.c:995 Read error on
> fd=3 at file:blk 0:0 on device "SDLT_MUC" (/dev/st0). ERR=Input/output
> error.
> 03-Jun 15:59 c06-dir JobId 36: Error: Unable to get Media record for
> Volume Vol_full_lib_MUC0027: ERR=sql_get.c:1005 Media record for Volume
> "Vol_full_lib_MUC0027" not found.
>
> 03-Jun 15:59 mastermind-sd JobId 36: Fatal error: Error getting Volume
> info: 1991 Catalog Request for vol=Vol_full_lib_MUC0027 failed:
> sql_get.c:1005 Media record for Volume "Vol_full_lib_MUC0027" not found.
> 03-Jun 15:59 mastermind-sd JobId 36: Job write elapsed time = 21:36:59,
> Transfer rate = 14.19 M bytes/second
> 03-Jun 15:59 c06-dir JobId 36: Fatal error: Catalog error creating
> JobMedia record. sql_create.c:156 Update Media record UPDATE Media SET
> EndFile=0, EndBlock=0 WHERE MediaId=0 failed: ERR=
> 03-Jun 15:59 mastermind-sd JobId 36: Fatal error: Error creating
> JobMedia record: 1991 Update JobMedia error
>
>
Now it appears that the catalog is not updating.
>
> 03-Jun 15:59 mastermind-sd JobId 36: Fatal error: Could not create
> JobMedia record for Volume="Vol_full_lib_MUC0027"
> Job=Library_MUC.2008-06-02_18.22.03
> 03-Jun 15:59 c06-dir JobId 36: Error: Unable to get Media record for
> Volume Vol_full_lib_MUC0027: ERR=sql_get.c:1005 Media record for Volume
> "Vol_full_lib_MUC0027" not found.
>
> 03-Jun 15:59 mastermind-sd JobId 36: Fatal error: Error getting Volume
> info: 1991 Catalog Request for vol=Vol_full_lib_MUC0027 failed:
> sql_get.c:1005 Media record for Volume "Vol_full_lib_MUC0027" not found.
> 03-Jun 15:57 munich-fd JobId 36: Fatal error: backup.c:892 Network send
> error to SD. ERR=Connection reset by peer
> 03-Jun 15:59 c06-dir JobId 36: Warning: Error getting Media record for
> Volume "Vol_full_lib_MUC0027": ERR=sql_get.c:1005 Media record for
> Volume "Vol_full_lib_MUC0027" not found.
> 03-Jun 15:59 c06-dir JobId 36: Error: Bacula c06-dir 2.2.8 (26Jan08):
> 03-Jun-2008 15:59:19
>  Build OS:               i686-pc-linux-gnu suse 10
>  JobId:                  36
>  Job:                    Library_MUC.2008-06-02_18.22.03
>  Backup Level:           Full
>  Client:                 "munich-fd" 2.2.8 (26Jan08)
> i686-pc-linux-gnu,suse,10
>  FileSet:                "Library_MUC" 2008-05-29 15:38:27
>  Pool:                   "Full_Lib_MUC" (From Job FullPool override)
>  Storage:                "Library_MUC" (From Job resource)
>  Scheduled time:         02-Jun-2008 18:22:04
>  Start time:             02-Jun-2008 18:22:09
>  End time:               03-Jun-2008 15:59:19
>  Elapsed time:           21 hours 37 mins 10 secs
>  Priority:               10
>  FD Files Written:       849,890
>  SD Files Written:       849,890
>  FD Bytes Written:       1,104,348,310,438 (1.104 TB)
>  SD Bytes Written:       1,104,555,001,510 (1.104 TB)
>  Rate:                   14189.2 KB/s
>  Software Compression:   None
>  VSS:                    no
>  Storage Encryption:     no
>  Volume name(s):         Vol_full_lib_MUC0018
>  Volume Session Id:      1
>  Volume Session Time:    1212423720
>  Last Volume Bytes:      0 (0 B)
>  Non-fatal FD errors:    0
>  SD Errors:              0
>  FD termination status:  Error
>  SD termination status:  Error
>  Termination:            *** Backup Error ***
>
> ---------------------------------------------------------------------
>
> I don't know what's wrong.
>
I would first change /dev/st0 to /dev/nst0 as having /dev/st0 can
cause problems with bacula and waste a lot of time as well.

After that I would do a repair on your database. I am not sure if
there is a database corruption or a bacula bug having to do with the
catalog.

John

-------------------------------------------------------------------------
This SF.net email is sponsored by: Microsoft
Defy all challenges. Microsoft(R) Visual Studio 2008.
http://clk.atdmt.com/MRT/go/vse0120000070mrt/direct/01/
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users

<Prev in Thread] Current Thread [Next in Thread>