Bacula-users

Re: [Bacula-users] restore runs infinite (long mail due debug output)

2008-06-30 16:26:40
Subject: Re: [Bacula-users] restore runs infinite (long mail due debug output)
From: Arno Lehmann <al AT its-lehmann DOT de>
To: bacula-users AT lists.sourceforge DOT net
Date: Mon, 30 Jun 2008 22:26:20 +0200
Hi,

30.06.2008 19:02, Udo Lembke wrote:
> Hello List,
> i use Bacula for a while, but now i have a problem to recover an 
> imap-archive from one user (just over 23000 Files, 14GB).
> If i start the recover (the backup-job is migrated to tapes) bat show 
> the job is running, but nothing happens. After an hour i cancel the job.
> Perhaps someone have a hint for me?
> I have got the data alredy back with bextract, but it should work with 
> bat also, i think.

Perhaps... have you tried the restore using bconsole?

After all, bat is still not officially ready for production use (so 
let's hope one of the Bat developers picks this up...) but if the same 
happens with bconsole I think it qualifies as a bug.

Actually, I believe the debug output you sent looks rather normal.

Do you have a chance to retry this restore using bconsole with an 
unstripped DIR and SD and attach gdb or strace to it, so the devlopers 
can get a "live view" of what those processes are doing?

Arno

> Best regards
> 
> Udo Lembke
> 
> Here ist the debug-output from sd (NMD264L2 is the right tape for the job):
> 
> admin-sd: dev.c:367-0 Try open "Drive-1" (/dev/nst0) mode=OPEN_READ_ONLY
> admin-sd: dev.c:389-0 Rewind after open
> admin-sd: bnet.c:667-0 who=client host=172.20.3.21 port=36643
> admin-sd: dircmd.c:173-0 Conn: Hello Director admin-dir calling
> admin-sd: dircmd.c:188-0 Got a DIR connection at 30-Jun-2008 17:53:26
> admin-sd: jcr.c:603-0 OnEntry JobStatus=admin-sd: jcr.c:623-0 OnExit 
> JobStatus=C set=C
> admin-sd: cram-md5.c:73-0 send: auth cram-md5 
> <1503317410.1214841206@admin-sd> ssl=0
> admin-sd: cram-md5.c:133-0 cram-get received: auth cram-md5 
> <170137960.1214841206@admin-dir> ssl=0
> admin-sd: cram-md5.c:152-0 sending resp to challenge: y3/KG8JNw4+eiAYJXQ8n7A
> admin-sd: dircmd.c:210-0 Message channel init completed.
> admin-sd: dev.c:2437-0 In set_os_device_parameters
> admin-sd: dev.c:2443-0 Set block size to zero
> admin-sd: dev.c:2459-0 MTSETDRVBUFFER
> admin-sd: dev.c:441-0 open dev: tape 3 opened
> admin-sd: dev.c:316-0 preserve=0x0 fd=3
> admin-sd: device.c:294-0 open dev "Drive-1" (/dev/nst0) OK
> admin-sd: label.c:81-0 Enter read_volume_label res=0 device="Drive-1" 
> (/dev/nst0) vol= dev_Vol=*NULL*
> admin-sd: label.c:149-0 Big if statement in read_volume_label
> admin-sd: label.c:215-0 Call reserve_volume=NMD264L2
> admin-sd: label.c:216-0 === dcr->dev=6867d8 dev=6867d8
> admin-sd: reserve.c:316-0 jid=0 enter reserve_volume=NMD264L2 drive="Drive-1" 
> (/dev/nst0)
> admin-sd: reserve.c:236-0 jid=0 new Vol=NMD264L2 at 6918d8 dev="Drive-1" 
> (/dev/nst0)
> admin-sd: reserve.c:422-0 jid=0 === set in_use. vol=NMD264L2 dev="Drive-1" 
> (/dev/nst0)
> admin-sd: reserve.c:189-0 jid=0 List end new volume: NMD264L2 in_use=1 on 
> device "Drive-1" (/dev/nst0)
> admin-sd: label.c:223-0 === dcr->dev=6867d8 dev=6867d8
> admin-sd: label.c:226-0 Compare Vol names: VolName=NMD264L2 hdr=NMD264L2
> admin-sd: label.c:243-0 Copy vol_name=NMD264L2
> 
> Volume Label:
> Id                : Bacula 1.0 immortal
> VerNo             : 11
> VolName           : NMD264L2
> PrevVolName       :
> VolFile           : 0
> LabelType         : VOL_LABEL
> LabelSize         : 157
> PoolName          : Default
> MediaType         : LTO-2
> PoolType          : Backup
> HostName          : admin
> Date label written: 08-Nov-2007 12:56
> admin-sd: label.c:248-0 Leave read_volume_label() VOL_OK
> admin-sd: reserve.c:568-0 jid=0 === set not reserved vol=NMD264L2 
> num_writers=0 dev_reserved=0 dev="Drive-1" (/dev/nst0)
> admin-sd: job.c:92-0 <dird: JobId=10065 job=RestoreFiles.2008-06-30_17.53.25 
> job_name=RestoreFiles client_name=mailgate-fd type=82 level=70 
> FileSet=web-srv3 NoAttr=0 SpoolAttr=0 FileSetMD5=**Dummy** SpoolData=0 
> WritePartAfterJob=1 PreferMountedVols=1
> admin-sd: job.c:156-0 >dird jid=10065: 3000 OK Job SDid=1 SDtime=1214841147 
> Authorization=KDAI-EDJL-FKKD-CEBB-DDJF-ADJE-MNCM-OKBA
> admin-sd: pythonlib.c:237-0 No startup module.
> admin-sd: reserve.c:708-0 jid=10065 <dird: use storage= media_type=LTO-2 
> pool_name=Default pool_type=Backup append=0 copy=0 stripe=0
> admin-sd: reserve.c:737-0 jid=10065 <dird device: use device=Drive-1
> admin-sd: reserve.c:754-0 jid=10065 Storage= media_type=LTO-2 pool=Default 
> pool_type=Backup append=0
> admin-sd: reserve.c:756-0 jid=10065     Device=Drive-1
> admin-sd: reserve.c:932-0 jid=10065 PrefMnt=1 exact=1 suitable=0 chgronly=0
> admin-sd: reserve.c:1097-0 jid=10065 search res for Drive-1
> admin-sd: reserve.c:1100-0 jid=10065 Try match changer res=ARCvault
> admin-sd: reserve.c:1129-0 jid=10065 Try match res=FileStorage
> admin-sd: reserve.c:1129-0 jid=10065 Try match res=Drive-1
> admin-sd: reserve.c:1169-0 jid=10065 chk MediaType device=LTO-2 request=LTO-2
> admin-sd: reserve.c:1192-0 try reserve Drive-1
> admin-sd: reserve.c:485-0 Inc reserve=0 dev="Drive-1" (/dev/nst0)
> admin-sd: reserve.c:1274-0 jid=10065 Read reserved=1 dev_name=Drive-1 
> mediatype=LTO-2 pool=Default ok=1
> admin-sd: reserve.c:1287-0 jid=10065 >dird changer: 3000 OK use device 
> device=Drive-1
> admin-sd: reserve.c:1144-0 jid=10065 Device Drive-1 reserved=1 for read.
> admin-sd: reserve.c:1066-0 jid=10065 available device found=Drive-1
> admin-sd: reserve.c:1081-0 OK dev found. Vol=
> admin-sd: jcr.c:603-0 OnEntry JobStatus=C set=F
> admin-sd: jcr.c:623-0 OnExit JobStatus=F set=F
> admin-sd: job.c:190-0 RestoreFiles.2008-06-30_17.53.25 waiting 1800 sec for 
> FD to contact SD key=KDAI-EDJL-FKKD-CEBB-DDJF-ADJE-MNCM-OKBA
> admin-sd: bnet.c:667-0 who=client host=62.206.40.27 port=36643
> admin-sd: dircmd.c:173-0 Conn: Hello Start Job 
> RestoreFiles.2008-06-30_17.53.25
> admin-sd: dircmd.c:179-0 Got a FD connection at 30-Jun-2008 17:54:26
> admin-sd: job.c:233-0 Found Job RestoreFiles.2008-06-30_17.53.25
> admin-sd: cram-md5.c:73-0 send: auth cram-md5 
> <1326064536.1214841266@admin-sd> ssl=0
> admin-sd: cram-md5.c:133-0 cram-get received: auth cram-md5 
> <864426312.1214841265@mailgate-fd> ssl=0
> admin-sd: cram-md5.c:152-0 sending resp to challenge: 7692O0/xJlsUaTQdb5+iwD
> admin-sd: job.c:250-0 OK Authentication jid=10065 Job 
> RestoreFiles.2008-06-30_17.53.25
> admin-sd: job.c:209-0 Running job RestoreFiles.2008-06-30_17.53.25
> admin-sd: fd_cmds.c:122-0 Start run Job=RestoreFiles.2008-06-30_17.53.25
> admin-sd: jcr.c:603-0 OnEntry JobStatus=F set=R
> admin-sd: jcr.c:623-0 OnExit JobStatus=R set=R
> admin-sd: fd_cmds.c:160-0 <filed: read open session =  1 1214841147 0 0 0 0
> admin-sd: fd_cmds.c:297-0 read open session =  1 1214841147 0 0 0 0
> 
> admin-sd: fd_cmds.c:312-0 read_open_session got: JobId=10065 Vol=1 
> VolSessId=1214841147 VolSessT=0
> admin-sd: fd_cmds.c:315-0   StartF=0 EndF=0 StartB=0 EndB=0
> admin-sd: fd_cmds.c:323-0 >filed: 3000 OK open ticket = 1
> admin-sd: fd_cmds.c:160-0 <filed: bootstrap
> admin-sd: fd_cmds.c:360-0 === Bootstrap file ===
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=0-178
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=12929
> admin-sd: fd_cmds.c:362-0 Count=1
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=190
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=63088
> admin-sd: fd_cmds.c:362-0 Count=1
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=234
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=189083-190811
> admin-sd: fd_cmds.c:362-0 Count=1729
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=235
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=190811-192674
> admin-sd: fd_cmds.c:362-0 Count=1864
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=236
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=192674-193684
> admin-sd: fd_cmds.c:362-0 Count=1011
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=237
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=193684-194578
> admin-sd: fd_cmds.c:362-0 Count=895
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=238
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=194578-195539
> admin-sd: fd_cmds.c:362-0 Count=962
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=239
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=195539-197086
> admin-sd: fd_cmds.c:362-0 Count=1548
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=240
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=197086-198798
> admin-sd: fd_cmds.c:362-0 Count=1713
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=241
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=198798-203243
> admin-sd: fd_cmds.c:362-0 Count=4446
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=242
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=203243-207871
> admin-sd: fd_cmds.c:362-0 Count=4629
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=243
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=207871-211652
> admin-sd: fd_cmds.c:362-0 Count=3782
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=244
> admin-sd: fd_cmds.c:362-0 VolBlock=0-15499
> admin-sd: fd_cmds.c:362-0 FileIndex=211652-212634
> admin-sd: fd_cmds.c:362-0 Count=983
> admin-sd: fd_cmds.c:362-0 Volume="NMD264L2"
> admin-sd: fd_cmds.c:362-0 MediaType="LTO-2"
> admin-sd: fd_cmds.c:362-0 Device="Drive-1"
> admin-sd: fd_cmds.c:362-0 Slot=5
> admin-sd: fd_cmds.c:362-0 VolSessionId=401
> admin-sd: fd_cmds.c:362-0 VolSessionTime=1212480361
> admin-sd: fd_cmds.c:362-0 VolFile=259
> admin-sd: fd_cmds.c:362-0 VolBlock=0-6159
> admin-sd: fd_cmds.c:362-0 FileIndex=271690
> admin-sd: fd_cmds.c:362-0 Count=1
> admin-sd: fd_cmds.c:366-0 === end bootstrap file ===
> Next        : 0x6934d8
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 0-178
> VolBlock    : 0-15499
> FileIndex   : 12929
> count       : 1
> found       : 0
> done        : no
> positioning : 1
> fast_reject : 1
> 
> Next        : 0x6938f8
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 190-190
> VolBlock    : 0-15499
> FileIndex   : 63088
> count       : 1
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x693d18
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 234-234
> VolBlock    : 0-15499
> FileIndex   : 189083-190811
> count       : 1729
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x694138
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 235-235
> VolBlock    : 0-15499
> FileIndex   : 190811-192674
> count       : 1864
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x694558
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 236-236
> VolBlock    : 0-15499
> FileIndex   : 192674-193684
> count       : 1011
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x694978
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 237-237
> VolBlock    : 0-15499
> FileIndex   : 193684-194578
> count       : 895
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x694d98
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 238-238
> VolBlock    : 0-15499
> FileIndex   : 194578-195539
> count       : 962
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x6951b8
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 239-239
> VolBlock    : 0-15499
> FileIndex   : 195539-197086
> count       : 1548
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x6955d8
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 240-240
> VolBlock    : 0-15499
> FileIndex   : 197086-198798
> count       : 1713
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x6959f8
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 241-241
> VolBlock    : 0-15499
> FileIndex   : 198798-203243
> count       : 4446
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x695e18
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 242-242
> VolBlock    : 0-15499
> FileIndex   : 203243-207871
> count       : 4629
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x696238
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 243-243
> VolBlock    : 0-15499
> FileIndex   : 207871-211652
> count       : 3782
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x696658
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 244-244
> VolBlock    : 0-15499
> FileIndex   : 211652-212634
> count       : 983
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> 
> Next        : 0x0
> Root bsr    : 0x692968
> VolumeName  : NMD264L2
>   MediaType : LTO-2
>   Device    : Drive-1
>   Slot      : 5
> SessId      : 401
> SessTime    : 1212480361
> VolFile     : 259-259
> VolBlock    : 0-6159
> FileIndex   : 271690
> count       : 1
> found       : 0
> done        : no
> positioning : 0
> fast_reject : 0
> admin-sd: fd_cmds.c:160-0 <filed: read data 1
> admin-sd: fd_cmds.c:277-0 Read data: read data 1
> admin-sd: fd_cmds.c:279-0 <bfiled: read data 1
> admin-sd: read.c:59-0 Start read data.
> admin-sd: acquire.c:113-0 MediaType dcr=LTO-2 dev=LTO-2
> admin-sd: reserve.c:316-0 jid=10065 enter reserve_volume=NMD264L2 
> drive="Drive-1" (/dev/nst0)
> admin-sd: reserve.c:189-0 jid=10065 List begin reserve_volume: NMD264L2 
> in_use=0 on device "Drive-1" (/dev/nst0)
> admin-sd: reserve.c:331-0 jid=10065 Vol attached=NMD264L2, newvol=NMD264L2 
> volinuse=0 on "Drive-1" (/dev/nst0)
> admin-sd: reserve.c:339-0 jid=10065 === set reserved vol=NMD264L2 
> dev="Drive-1" (/dev/nst0)
> admin-sd: reserve.c:422-0 jid=10065 === set in_use. vol=NMD264L2 
> dev="Drive-1" (/dev/nst0)
> admin-sd: reserve.c:189-0 jid=10065 List end new volume: NMD264L2 in_use=1 on 
> device "Drive-1" (/dev/nst0)
> admin-sd: askdir.c:230-0 >dird CatReq Job=RestoreFiles.2008-06-30_17.53.25 
> GetVolInfo VolName=NMD264L2 write=0
> admin-sd: askdir.c:182-0 <dird 1000 OK VolName=NMD264L2 VolJobs=91 
> VolFiles=311 VolBlocks=3897685 VolBytes=251447519232 VolMounts=5 VolErrors=0 
> VolWrites=20789539 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Full Slot=5 
> MaxVolJobs=0 MaxVolFiles=0 InChanger=1 VolReadTime=2749507104 
> VolWriteTime=19208922860 EndFile=310 EndBlock=0 VolParts=0 LabelType=0 
> MediaId=17
> admin-sd: askdir.c:205-0 do_reqest_vol_info return true slot=5 Volume=NMD264L2
> admin-sd: mount.c:502-0 swapping: must load "Drive-1" (/dev/nst0)
> admin-sd: autochanger.c:132-0 autoload: slot=5 InChgr=1 Vol=NMD264L2
> admin-sd: autochanger.c:220-0 After changer, status=0
> admin-sd: acquire.c:223-0 bstored: open vol=NMD264L2
> admin-sd: acquire.c:231-0 opened dev "Drive-1" (/dev/nst0) OK
> admin-sd: acquire.c:234-0 calling read-vol-label
> admin-sd: label.c:81-0 Enter read_volume_label res=1 device="Drive-1" 
> (/dev/nst0) vol=NMD264L2 dev_Vol=NMD264L2
> admin-sd: label.c:149-0 Big if statement in read_volume_label
> admin-sd: label.c:215-0 Call reserve_volume=NMD264L2
> admin-sd: label.c:216-0 === dcr->dev=6867d8 dev=6867d8
> admin-sd: reserve.c:316-0 jid=10065 enter reserve_volume=NMD264L2 
> drive="Drive-1" (/dev/nst0)
> admin-sd: reserve.c:189-0 jid=10065 List begin reserve_volume: NMD264L2 
> in_use=1 on device "Drive-1" (/dev/nst0)
> admin-sd: reserve.c:331-0 jid=10065 Vol attached=NMD264L2, newvol=NMD264L2 
> volinuse=1 on "Drive-1" (/dev/nst0)
> admin-sd: reserve.c:339-0 jid=10065 === set reserved vol=NMD264L2 
> dev="Drive-1" (/dev/nst0)
> admin-sd: reserve.c:422-0 jid=10065 === set in_use. vol=NMD264L2 
> dev="Drive-1" (/dev/nst0)
> admin-sd: reserve.c:189-0 jid=10065 List end new volume: NMD264L2 in_use=1 on 
> device "Drive-1" (/dev/nst0)
> admin-sd: label.c:223-0 === dcr->dev=6867d8 dev=6867d8
> admin-sd: label.c:226-0 Compare Vol names: VolName=NMD264L2 hdr=NMD264L2
> admin-sd: label.c:243-0 Copy vol_name=NMD264L2
> 
> Volume Label:
> Id                : Bacula 1.0 immortal
> VerNo             : 11
> VolName           : NMD264L2
> PrevVolName       :
> VolFile           : 0
> LabelType         : VOL_LABEL
> LabelSize         : 157
> PoolName          : Default
> MediaType         : LTO-2
> PoolType          : Backup
> HostName          : admin
> Date label written: 08-Nov-2007 12:56
> admin-sd: label.c:248-0 Leave read_volume_label() VOL_OK
> admin-sd: jcr.c:603-0 OnEntry JobStatus=R set=R
> admin-sd: jcr.c:623-0 OnExit JobStatus=R set=R
> admin-sd: reserve.c:494-0 Dec reserve=0 dev="Drive-1" (/dev/nst0)
> 
> ###### after this point nothing happens....
> 
> 

-- 
Arno Lehmann
IT-Service Lehmann
www.its-lehmann.de

-------------------------------------------------------------------------
Check out the new SourceForge.net Marketplace.
It's the best place to buy or sell services for
just about anything Open Source.
http://sourceforge.net/services/buy/index.php
_______________________________________________
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>