Bacula-users

[Bacula-users] bacula-sd: Bacula interrupted by signal 11: Segmentation violation

2012-07-19 05:54:40
Subject: [Bacula-users] bacula-sd: Bacula interrupted by signal 11: Segmentation violation
From: Tobias Dinse <tobias.dinse AT stegbauer DOT info>
To: bacula-users AT lists.sourceforge DOT net
Date: Thu, 19 Jul 2012 11:35:16 +0200
Hi all,

yesterday we have moved our old physical Servers to an esxi 5 Environment. I created an new VM with Debian 6 x64 - Hardware passtrough for the Controller (h200 / IBM LTO3 Streamer) and bacula-sd-mysql Version 5.2.6

I can label / mount all Volumes fine. But if I start an Backup Job the bacula-sd services crasehs with the follow messages:

bacula-dir error messages:
19-Jul 11:24 lx02-oracle-ourcustomer-dir JobId 4143: Start Backup JobId 4143, Job=lx01-gw01-ourcustomer.2012-07-19_11.24.06_08
19-Jul 11:24 lx02-oracle-ourcustomer-dir JobId 4143: Fatal error: Storage daemon rejected Job command: 3915 Bad Job command. stat=-1 CMD: JobId=4143 job=lx01-gw01-ourcustomer.2012-07-19_11.24.06_08 job_name=lx01-gw01-ourcustomer client_name=lx01-gw01-marktl-fd type=66 level=70 FileSet=FullSet NoAttr=0 SpoolAttr=0 FileSetMD5=uRUmnm122BMpY7QWJj/eCC SpoolData=1 WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 Resched=2616184

syslog:
bacula-sd: Bacula interrupted by signal 11: Segmentation violation

bacula-sd Debug output:
lx03-baculasd-ourcustomer-sd: bnet.c:708-0 who=client host=192.168.2.1 port=36643
lx03-baculasd-ourcustomer-sd: dircmd.c:175-0 Conn: Hello Director lx02-oracle-ourcustomer-dir calling
lx03-baculasd-ourcustomer-sd: dircmd.c:191-0 Got a DIR connection at 19-Jul-2012 11:23:48
lx03-baculasd-ourcustomer-sd: cram-md5.c:72-0 send: auth cram-md5 <547954107.1342689828@lx03-baculasd-ourcustomer-sd> ssl=0
lx03-baculasd-ourcustomer-sd: cram-md5.c:131-0 cram-get received: auth cram-md5 <1116653603.1342689828@lx02-oracle-ourcustomer-dir> ssl=0
lx03-baculasd-ourcustomer-sd: cram-md5.c:150-0 sending resp to challenge: q/+s4+/CM4RLC3+FT++4tC
lx03-baculasd-ourcustomer-sd: dircmd.c:213-0 Message channel init completed.
lx03-baculasd-ourcustomer-sd: dircmd.c:220-0 <dird: mount IBM-LTO3-sd drive=-1
lx03-baculasd-ourcustomer-sd: dircmd.c:234-0 Do command: mount
lx03-baculasd-ourcustomer-sd: dircmd.c:668-0 ok=1 drive=-1 slot=0
lx03-baculasd-ourcustomer-sd: dircmd.c:602-0 Found device IBM-LTO3-sd
lx03-baculasd-ourcustomer-sd: dircmd.c:642-0 Found device IBM-LTO3-sd
lx03-baculasd-ourcustomer-sd: dircmd.c:675-0 mount cmd blocked=1 must_unload=0
lx03-baculasd-ourcustomer-sd: dircmd.c:692-0 Unmounted changer=4096 slot=0
lx03-baculasd-ourcustomer-sd: dev.c:376-0 open dev: type=2 dev_name="IBM-LTO3-sd" (/dev/nst0) vol= mode=OPEN_READ_ONLY
lx03-baculasd-ourcustomer-sd: dev.c:1896-0 Enter mount
lx03-baculasd-ourcustomer-sd: dev.c:426-0 Open dev: device is tape
lx03-baculasd-ourcustomer-sd: dev.c:441-0 Try open "IBM-LTO3-sd" (/dev/nst0) mode=OPEN_READ_ONLY
lx03-baculasd-ourcustomer-sd: dev.c:463-0 Rewind after open
lx03-baculasd-ourcustomer-sd: dev.c:2392-0 In set_os_device_parameters
lx03-baculasd-ourcustomer-sd: dev.c:2398-0 Set block size to zero
lx03-baculasd-ourcustomer-sd: dev.c:2414-0 MTSETDRVBUFFER
lx03-baculasd-ourcustomer-sd: dev.c:515-0 open dev: tape 5 opened
lx03-baculasd-ourcustomer-sd: dev.c:389-0 preserve=0x0 fd=5
lx03-baculasd-ourcustomer-sd: label.c:79-0 Enter read_volume_label res=0 device="IBM-LTO3-sd" (/dev/nst0) vol= dev_Vol=*NULL*
lx03-baculasd-ourcustomer-sd: label.c:128-0 Big if statement in read_volume_label
lx03-baculasd-ourcustomer-sd: label.c:822-0 unser_vol_label

Volume Label:
Id                : Bacula 1.0 immortal
VerNo             : 11
VolName           : MITT01L3
PrevVolName       :
VolFile           : 0
LabelType         : VOL_LABEL
LabelSize         : 190
PoolName          : MittwochPool
MediaType         : LTO-3
PoolType          : Backup
HostName          : lx03-baculasd-ourcustomer
Date label written: 18-Jul-2012 16:25
lx03-baculasd-ourcustomer-sd: label.c:200-0 Compare Vol names: VolName= hdr=MITT01L3

Volume Label:
Id                : Bacula 1.0 immortal
VerNo             : 11
VolName           : MITT01L3
PrevVolName       :
VolFile           : 0
LabelType         : VOL_LABEL
LabelSize         : 190
PoolName          : MittwochPool
MediaType         : LTO-3
PoolType          : Backup
HostName          : lx03-baculasd-ourcustomer
Date label written: 18-Jul-2012 16:25
lx03-baculasd-ourcustomer-sd: label.c:221-0 Leave read_volume_label() VOL_OK
lx03-baculasd-ourcustomer-sd: label.c:234-0 Call reserve_volume=MITT01L3
lx03-baculasd-ourcustomer-sd: vol_mgr.c:349-0 enter reserve_volume=MITT01L3 drive="IBM-LTO3-sd" (/dev/nst0)
lx03-baculasd-ourcustomer-sd: vol_mgr.c:265-0 new Vol=MITT01L3 at 1600368 dev="IBM-LTO3-sd" (/dev/nst0)
lx03-baculasd-ourcustomer-sd: vol_mgr.c:467-0 === set in_use. vol=MITT01L3 dev="IBM-LTO3-sd" (/dev/nst0)
lx03-baculasd-ourcustomer-sd: vol_mgr.c:208-0 List end new volume: MITT01L3 in_use=1 on device "IBM-LTO3-sd" (/dev/nst0)
lx03-baculasd-ourcustomer-sd: dircmd.c:710-0 Unmounted. Unblocking device
lx03-baculasd-ourcustomer-sd: label.c:79-0 Enter read_volume_label res=0 device="IBM-LTO3-sd" (/dev/nst0) vol= dev_Vol=MITT01L3
lx03-baculasd-ourcustomer-sd: label.c:128-0 Big if statement in read_volume_label
lx03-baculasd-ourcustomer-sd: label.c:822-0 unser_vol_label

Volume Label:
Id                : Bacula 1.0 immortal
VerNo             : 11
VolName           : MITT01L3
PrevVolName       :
VolFile           : 0
LabelType         : VOL_LABEL
LabelSize         : 190
PoolName          : MittwochPool
MediaType         : LTO-3
PoolType          : Backup
HostName          : lx03-baculasd-ourcustomer
Date label written: 18-Jul-2012 16:25
lx03-baculasd-ourcustomer-sd: label.c:200-0 Compare Vol names: VolName= hdr=MITT01L3

Volume Label:
Id                : Bacula 1.0 immortal
VerNo             : 11
VolName           : MITT01L3
PrevVolName       :
VolFile           : 0
LabelType         : VOL_LABEL
LabelSize         : 190
PoolName          : MittwochPool
MediaType         : LTO-3
PoolType          : Backup
HostName          : lx03-baculasd-ourcustomer
Date label written: 18-Jul-2012 16:25
lx03-baculasd-ourcustomer-sd: label.c:221-0 Leave read_volume_label() VOL_OK
lx03-baculasd-ourcustomer-sd: label.c:234-0 Call reserve_volume=MITT01L3
lx03-baculasd-ourcustomer-sd: vol_mgr.c:349-0 enter reserve_volume=MITT01L3 drive="IBM-LTO3-sd" (/dev/nst0)
lx03-baculasd-ourcustomer-sd: vol_mgr.c:208-0 List begin reserve_volume: MITT01L3 in_use=1 on device "IBM-LTO3-sd" (/dev/nst0)
lx03-baculasd-ourcustomer-sd: vol_mgr.c:364-0 Vol attached=MITT01L3, newvol=MITT01L3 volinuse=1 on "IBM-LTO3-sd" (/dev/nst0)
lx03-baculasd-ourcustomer-sd: vol_mgr.c:372-0 === set reserved vol=MITT01L3 dev="IBM-LTO3-sd" (/dev/nst0)
lx03-baculasd-ourcustomer-sd: vol_mgr.c:467-0 === set in_use. vol=MITT01L3 dev="IBM-LTO3-sd" (/dev/nst0)
lx03-baculasd-ourcustomer-sd: vol_mgr.c:208-0 List end new volume: MITT01L3 in_use=1 on device "IBM-LTO3-sd" (/dev/nst0)
lx03-baculasd-ourcustomer-sd: vol_mgr.c:591-0 === set not reserved vol=MITT01L3 num_writers=0 dev_reserved=0 dev="IBM-LTO3-sd" (/dev/nst0)
lx03-baculasd-ourcustomer-sd: vol_mgr.c:592-0 === clear in_use vol=MITT01L3
lx03-baculasd-ourcustomer-sd: dircmd.c:726-0 JobId=0 broadcast wait_device_release
lx03-baculasd-ourcustomer-sd: pythonlib.c:225-0 No startup module.
lx03-baculasd-ourcustomer-sd: bnet.c:708-0 who=client host=192.168.2.1 port=36643
lx03-baculasd-ourcustomer-sd: dircmd.c:175-0 Conn: Hello Director lx02-oracle-ourcustomer-dir calling
lx03-baculasd-ourcustomer-sd: dircmd.c:191-0 Got a DIR connection at 19-Jul-2012 11:24:08
lx03-baculasd-ourcustomer-sd: cram-md5.c:72-0 send: auth cram-md5 <107389492.1342689848@lx03-baculasd-ourcustomer-sd> ssl=0
lx03-baculasd-ourcustomer-sd: cram-md5.c:131-0 cram-get received: auth cram-md5 <1457482404.1342689848@lx02-oracle-ourcustomer-dir> ssl=0
lx03-baculasd-ourcustomer-sd: cram-md5.c:150-0 sending resp to challenge: mQFbs4RnI6Edd7+Fp4+KtA
lx03-baculasd-ourcustomer-sd: dircmd.c:213-0 Message channel init completed.
lx03-baculasd-ourcustomer-sd: dircmd.c:220-0 <dird: JobId=4143 job=lx01-gw01-ourcustomer.2012-07-19_11.24.06_08 job_name=lx01-gw01-ourcustomer client_name=lx01-gw01-ourcustomer-fd type=66 level=70 FileSet=FullSet NoAttr=0 SpoolAttr=0 FileSetMD5=uRUmnm122BMpY7QWJj/eCC SpoolData=1 WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 Resched=2616184

lx03-baculasd-ourcustomer-sd: dircmd.c:234-0 Do command: JobId=
lx03-baculasd-ourcustomer-sd: job.c:84-0 <dird: JobId=4143 job=lx01-gw01-ourcustomer.2012-07-19_11.24.06_08 job_name=lx01-gw01-ourcustomer client_name=lx01-gw01-ourcustomer-fd type=66 level=70 FileSet=FullSet NoAttr=0 SpoolAttr=0 FileSetMD5=uRUmnm122BMpY7QWJj/eCC SpoolData=1 WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 Resched=2616184
lx03-baculasd-ourcustomer-sd: job.c:95-0 >dird: 3915 Bad Job command. stat=-1 CMD: JobId=4143 job=lx01-gw01-ourcustomer.2012-07-19_11.24.06_08 job_name=lx01-gw01-ourcustomer client_name=lx01-gw01-ourcustomer-fd type=66 level=70 FileSet=FullSet NoAttr=0 SpoolAttr=0 FileSetMD5=uRUmnm122BMpY7QWJj/eCC SpoolData=1 WritePartAfterJob=1 PreferMountedVols=1 SpoolSize=0 Resched=2616184

lx03-baculasd-ourcustomer-sd: dircmd.c:237-0 Command JobId= requests quit
lx03-baculasd-ourcustomer-sd: pythonlib.c:225-0 No startup module.

------------------------------------------------------------------------------
Live Security Virtual Conference
Exclusive live event will cover all the ways today's security and 
threat landscape has changed and how IT managers can respond. Discussions 
will include endpoint security, mobile security and the latest in malware 
threats. http://www.accelacomm.com/jaw/sfrnl04242012/114/50122263/
_______________________________________________
Bacula-users mailing list
Bacula-users AT lists.sourceforge DOT net
https://lists.sourceforge.net/lists/listinfo/bacula-users