Bacula-users

[Bacula-users] 2 Drive Autchanger, copy-job deadlock

2012-03-23 07:12:24
Subject: [Bacula-users] 2 Drive Autchanger, copy-job deadlock
From: Hugo Cardoso <hugo.alex.cardoso AT gmail DOT com>
To: bacula-users AT lists.sourceforge DOT net
Date: Fri, 23 Mar 2012 11:10:30 +0000
Hi all,

I'm still trying to implement bacula in my organization.
And meanwhile I've stumbled in one problem that i can't figure out or
even find any information in the several mailing lists.

I have a 2 drive tape library and some times my copy jobs(tape to
tape) get blocked because one job requests one tape on drive 0 when
the same tape is mounted on drive 1 from a previous finished job.
When i try to manually release the tape on drive 1 i get the message

"3937 Device ""Drive-1" (/dev/st1)" is busy with writers=0 reserved=1"

All previous jobs have terminated without errors.
I just don't understand why it doesn't  unmount the tape on drive 1

This problem only happens with my copy jobs.

Have anyone had this kind of behaviour from bacula ?

I am using bacula 5.2.6 (Same behaviour on 5.2.5 ) and my configuration is:

-Pools

1 Disk Pool
2 Tape pools (One of this pools is for offsite)

-Schedule

Full backup at Sunday
Incrementals every day except Sunday
Every day runs the copy to offsite (Only starts long after the main
backups finished)

-Jobs

12 Jobs to disk pool
5 Jobs to tape pool (These are to big for my disk pool, combined have ~1TB )
2 Copy jobs to my offsite tape pool
     One to copy from disk pool to offsite tape pool
     Another to copy from tape pool to offsite tape pool

Here is the log of the blocked job, he doesn't even try to unload drive 1:

20-Mar 07:18 coimbra-dir JobId 1756: Start Copying JobId 1756,
Job=CopyTapeToOffsite.2012-03-20_06.30.01_41
20-Mar 07:23 coimbra-dir JobId 1756: Using Device "Drive-1"
20-Mar 07:23 coimbra-sd JobId 1756: 3307 Issuing autochanger "unload
slot 25, drive 0" command.
20-Mar 07:24 coimbra-sd JobId 1756: 3301 Issuing autochanger "loaded?
drive 0" command.
20-Mar 07:24 coimbra-sd JobId 1756: 3302 Autochanger "loaded? drive
0", result: nothing loaded.
20-Mar 07:27 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 07:27 coimbra-sd JobId 1756: 3301 Issuing autochanger "loaded?
drive 0" command.
20-Mar 07:27 coimbra-sd JobId 1756: 3302 Autochanger "loaded? drive
0", result: nothing loaded.
20-Mar 07:27 coimbra-sd JobId 1756: Warning: acquire.c:239 Read open
device "Drive-0" (/dev/st0) Volume "NFQ034L2" failed: ERR=dev.c:506
Unable to open device "Drive-0" (/dev/st0): ERR=No medium found

20-Mar 07:27 coimbra-sd JobId 1756: 3301 Issuing autochanger "loaded?
drive 0" command.
20-Mar 07:27 coimbra-sd JobId 1756: 3302 Autochanger "loaded? drive
0", result: nothing loaded.
20-Mar 07:27 coimbra-sd JobId 1756: 3301 Issuing autochanger "loaded?
drive 0" command.
20-Mar 07:27 coimbra-sd JobId 1756: 3302 Autochanger "loaded? drive
0", result: nothing loaded.
20-Mar 07:30 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 07:30 coimbra-sd JobId 1756: Please mount Volume "NFQ034L2" for:
    Job:          CopyTapeToOffsite.2012-03-20_06.30.01_41
    Storage:      "Drive-0" (/dev/st0)
    Pool:         TapePool
    Media type:   LTO-2
20-Mar 07:38 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 07:41 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 07:49 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 07:52 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 08:00 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 08:03 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 08:11 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 08:14 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 08:22 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 08:25 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 08:33 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)
20-Mar 08:36 coimbra-sd JobId 1756: Warning: Volume "NFQ034L2" wanted
on "Drive-0" (/dev/st0) is in use by device "Drive-1" (/dev/st1)

Thanks in advance
HC

------------------------------------------------------------------------------
This SF email is sponsosred by:
Try Windows Azure free for 90 days Click Here 
http://p.sf.net/sfu/sfd2d-msazure
_______________________________________________
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>