This is a bug where a unlocked mutex was unlocked a second time. It has been
fixed in version 1.38.2.
On Monday 05 December 2005 08:52, rkvam wrote:
> Here`s a trace to:
> warning: Unable to get location for thread creation breakpoint: generic
> error
> [New Thread 0x80c2000 (runnable)]
> [New Thread 0x80a8a00 (sleeping)]
> [New Thread 0x80a8600 (LWP 100092)]
> [New Thread 0x80a8000 (runnable)]
> [New LWP 100195]
> [Switching to LWP 100195]
> 0x280eb277 in pthread_testcancel () from /usr/lib/libpthread.so.2
> $1 = "alexandria-sd", '\0' <repeats 16 times>
> $2 = 0x80ab018 "bacula-sd"
> $3 = 0x80ab058 "/usr/local/bin/bacula-sd"
> $4 = "MySQL"
> $5 = 0x8090424 "1.38.0 (28 October 2005)"
> $6 = 0x808db8d "i386-unknown-freebsd6.0"
> $7 = 0x808db85 "freebsd"
> $8 = 0x808db79 "6.0-RELEASE"
> #0 0x280eb277 in pthread_testcancel () from /usr/lib/libpthread.so.2
> #1 0x280e46f3 in pthread_mutexattr_init () from /usr/lib/libpthread.so.2
> #2 0x080c2000 in ?? ()
>
> Thread 5 (LWP 100195):
> #0 0x280eb277 in pthread_testcancel () from /usr/lib/libpthread.so.2
> #1 0x280e46f3 in pthread_mutexattr_init () from /usr/lib/libpthread.so.2
> #2 0x080c2000 in ?? ()
>
> Thread 4 (Thread 0x80a8000 (runnable)):
> #0 0x2821e0b3 in select () from /lib/libc.so.6
> #1 0x280d4639 in select () from /usr/lib/libpthread.so.2
> #2 0x080710a1 in bnet_thread_server (addrs=0x80ab258, max_clients=21,
> client_wq=0x809e2c0,
> handle_client_request=0x805a214 <handle_connection_request(void*)>)
> at bnet_server.c:148
> #3 0x0804b4f1 in main (argc=0, argv=0x809e8c0) at stored.c:240
>
> Thread 3 (Thread 0x80a8600 (LWP 100092)):
> #0 0x280eb277 in pthread_testcancel () from /usr/lib/libpthread.so.2
> #1 0x280e3dac in pthread_mutexattr_init () from /usr/lib/libpthread.so.2
> #2 0x00000000 in ?? ()
>
> Thread 2 (Thread 0x80a8a00 (sleeping)):
> #0 0x280e3e7f in pthread_mutexattr_init () from /usr/lib/libpthread.so.2
> #1 0x280e4013 in pthread_mutexattr_init () from /usr/lib/libpthread.so.2
> #2 0x280e8dd9 in _pthread_cond_timedwait () from /usr/lib/libpthread.so.2
> #3 0x280e9342 in pthread_cond_timedwait () from /usr/lib/libpthread.so.2
> #4 0x08081276 in watchdog_thread (arg=0x0) at watchdog.c:296
> #5 0x280d6ab1 in pthread_create () from /usr/lib/libpthread.so.2
> #6 0x2827945f in _ctx_start () from /lib/libc.so.6
>
> Thread 1 (Thread 0x80c2000 (runnable)):
> #0 0x2827945c in _ctx_start () from /lib/libc.so.6
> #1 0x2827945f in _ctx_start () from /lib/libc.so.6
> #2 0x00000000 in ?? ()
> #3 0xbf6fa8f0 in ?? ()
> #4 0xbf6fa630 in ?? ()
> #5 0x00000000 in ?? ()
> #6 0x280e2c40 in pthread_mutexattr_init () from /usr/lib/libpthread.so.2
> #7 0x0806dc7d in _v (m=0x80a2480) at berrno.h:69
> #8 0x0804c927 in unlock_changer (dcr=0x7) at autochanger.c:257
> #9 0x0804d545 in autoload_device (dcr=0x80c5018, writing=0, dir=0x80a2a18)
> at autochanger.c:185
> #10 0x0805b69e in try_autoload_device (jcr=0x7, slot=11, VolName=0x0)
> at dircmd.c:925
> #11 0x0805b73e in label_volume_if_ok (jcr=0x80ae418, dev=0x80ac028,
> oldname=0x80a2d28 "", newname=0x80a2c28 "000011L2",
> poolname=0x80a2e28 "FullTape0", slot=11, relabel=0) at dircmd.c:391
> #12 0x0805bb88 in do_label (jcr=0x80ae418, relabel=0) at dircmd.c:352
> #13 0x0805bc1c in label_cmd (jcr=0x7) at dircmd.c:295
> #14 0x0805a567 in handle_connection_request (arg=0x80a2a18) at dircmd.c:209
> #15 0x08081c89 in workq_server (arg=0x809e2c0) at workq.c:347
> #16 0x280d6ab1 in pthread_create () from /usr/lib/libpthread.so.2
> #17 0x2827945f in _ctx_start () from /lib/libc.so.6
> #0 0x280eb277 in pthread_testcancel () from /usr/lib/libpthread.so.2
> #0 0x280eb277 in pthread_testcancel () from /usr/lib/libpthread.so.2
> No symbol table info available.
> #1 0x280e46f3 in pthread_mutexattr_init () from /usr/lib/libpthread.so.2
> No symbol table info available.
> #2 0x080c2000 in ?? ()
> No symbol table info available.
> #0 0x00000000 in ?? ()
> No symbol table info available.
> #0 0x00000000 in ?? ()
> No symbol table info available.
> #0 0x00000000 in ?? ()
> No symbol table info available.
> #0 0x00000000 in ?? ()
> No symbol table info available.
> #0 0x00000000 in ?? ()
> No symbol table info available.
>
> and the bacula-sd crashes :(
>
> Roger Kvam wrote:
> > When trying to run a backup-job, bacula loads the correct tape, but fail
> > to use it.
> > If I manualy load correct tape, and in the console mount the drive
> > before starting the job, the job runs correct, the changer seams to work
> > well to, as it unload a tape, and inserting the correct tape. I have
> > tried to add a "sleep 10" in the load command in the chio-changer
> > script, without luck.
> >
> > All tests with the "btape test" ran flawless.
> > ################
> > #Error message:#
> > ################
> > 30-Nov 21:26 alexandria-dir: Start Backup JobId 33,
> > Job=PatMailBackup.2005-11-30_21.26.06
> > 30-Nov 20:26 pat: DIR and FD clocks differ by -3598 seconds, FD
> > automatically adjusting.
> > 30-Nov 21:26 alexandria-sd: 3301 Issuing autochanger "loaded drive 0"
> > command.
> > 30-Nov 21:26 alexandria-sd: 3302 Autochanger "loaded drive 0", result is
> > Slot 9.
> > 30-Nov 21:26 alexandria-sd: 3307 Issuing autochanger "unload slot 9,
> > drive 0" command.
> > 30-Nov 21:27 alexandria-sd: 3304 Issuing autochanger "load slot 1, drive
> > 0" command.
> > 30-Nov 21:27 alexandria-sd: 3305 Autochanger "load slot 1, drive 0",
> > status is OK.
> > 30-Nov 20:27 pat: PatMailBackup.2005-11-30_21.26.06 Fatal error:
> > job.c:1662 Comm error with SD. bad response to Append Data. ERR=Resource
> > temporarily unavailable
> > 30-Nov 21:27 alexandria-dir: PatMailBackup.2005-11-30_21.26.06 Error:
> > Bacula 1.38.0 (28Oct05): 30-Nov-2005 21:27:54
> > ########################################################################
> > Here is a log from a successful backup when correct tape is loaded and #
> > mounted before job is started: #
> > ########################################################################
> > 02-Dec 18:02 alexandria-dir: Start Backup JobId 41,
> > Job=PatMailBackup.2005-12-02_18.02.33
> > 02-Dec 17:02 pat: DIR and FD clocks differ by -3598 seconds, FD
> > automatically adjusting.
> > 02-Dec 18:02 alexandria-sd: 3301 Issuing autochanger "loaded drive 0"
> > command.
> > 02-Dec 18:02 alexandria-sd: 3302 Autochanger "loaded drive 0", result is
> > Slot 2.
> > 02-Dec 18:02 alexandria-sd: 3301 Issuing autochanger "loaded drive 0"
> > command.
> > 02-Dec 18:02 alexandria-sd: 3302 Autochanger "loaded drive 0", result is
> > Slot 2.
> > 02-Dec 18:02 alexandria-sd: Wrote label to prelabeled Volume "000002L2"
> > on device "TapeLib0-Drive0" (/dev/nsa0)
> > 02-Dec 18:02 alexandria-sd: Spooling data ...
> > 02-Dec 18:05 alexandria-sd: Committing spooled data to Volume.
> > Despooling 1,314,803,267 bytes ...
> > 02-Dec 18:06 alexandria-sd: Sending spooled attrs to the Director.
> > Despooling 3,921,248 bytes ...
> > 02-Dec 18:06 alexandria-dir: Bacula 1.38.0 (28Oct05): 02-Dec-2005
> > 18:06:36 JobId: 41
> > Job: PatMailBackup.2005-12-02_18.02.33
> > Backup Level: Incremental, since=2005-11-29 13:13:54
> > Client: "Pat"
> > i386-unknown-freebsd5.2.1,freebsd,5.2.1-RELEASE
> > FileSet: "PatFileSet" 2005-11-29 00:05:02
> > Pool: "IncrementalTape0"
> > Storage: "Autochanger0"
> > Scheduled time: 02-Dec-2005 18:02:30
> > Start time: 02-Dec-2005 18:02:35
> > End time: 02-Dec-2005 18:06:36
> > Priority: 10
> > FD Files Written: 17,923
> > SD Files Written: 17,923
> > FD Bytes Written: 1,310,628,325
> > SD Bytes Written: 1,313,044,041
> > Rate: 5438.3 KB/s
> > Software Compression: None
> > Volume name(s): 000002L2
> > Volume Session Id: 1
> > Volume Session Time: 1133546529
> > Last Volume Bytes: 1,314,558,744
> > Non-fatal FD errors: 0
> > SD Errors: 0
> > FD termination status: OK
> > SD termination status: OK
> > Termination: Backup OK
> >
> > here are my relevant configurations:
> > ###########
> > #Director:#
> > ###########
> > Job {
> > Name = "PatMailBackup"
> > Client = Pat
> > Type = Backup
> > Messages = Standard
> > Storage = Autochanger0
> > FileSet="PatFileSet"
> > Schedule = "NightlyTape"
> > Priority = 10
> > Level = Incremental
> > Pool = IncrementalTape0
> > Full Backup Pool = FullTape0
> > SpoolData = yes
> > }
> > Storage {
> > Name = Autochanger0
> > Device = TapeLib0-Drive0, TapeLib0-Drive1
> > Address = xxxx.xxxxx.xxx
> > SDPort = 9103
> > Password = "iTxZosxNduiE6pcxASWch7CzW6CevcK8eFDqptmXFBwc"
> > Device = "Autochanger0"
> > Media Type = LTO-2
> > Autochanger = yes
> > }
> >
> > ###############
> > #Storagedaemon#
> > ###############
> > Autochanger {
> > Name = "Autochanger0"
> > Device = TapeLib0-Drive0, TapeLib0-Drive1
> > Changer Device = /dev/sh0
> > Changer Command = "/usr/local/sbin/chio-changer %o %S %a"
> > }
> >
> > Device {
> > Name = "TapeLib0-Drive0"
> > Media Type = LTO-2
> > Archive Device = /dev/nsa0 # Normal archive device
> > Changer Device = /dev/ch0 # Generic SCSI device name
> > # Changer Command = "/usr/local/sbin/mtx-changer %c %o %S %a %d"
> > # Changer Command = "/usr/local/sbin/chio-changer %c %o %S %a"
> > Changer Command = "/usr/local/sbin/chio-changer %o %S %a"
> > Autochanger = yes
> > LabelMedia = no
> > AutomaticMount = yes
> > AlwaysOpen = yes
> > ############### for testing ##############
> > Backward Space Record = no
> > Backward Space File = no
> > #########################################
> > Hardware End of Medium = no
> > Fast Forward Space File = yes
> > BSF at EOM = yes
> > Drive Index = 0
> > TWO EOF = yes
> > }
> >
> > TapeLib0-Drive1 is identical to TapeLib0-Drive0, but has never been used.
> >
> > Everyting compiled from source on FreeBSD 6.0 Release I386
> > Bacula source: 1.38
> >
> > ./configure \
> > --sbindir=/usr/local/bin \
> > --sysconfdir=/usr/local/etc/bacula \
> > --with-pid-dir=/var/run/bacula \
> > --with-subsys-dir=/usr/local/bacula/bin \
> > --with-mysql=yes \
> > --with-working-dir=/usr/local/bacula/bin/working \
> > [EMAIL PROTECTED] \
> > --with-smtp-host=smtp.xxxx.xxxx.xxx \
> > --with-dir_user=bacula \
> > --with-fd_user=bacula \
> > --with-sd_user=bacula \
> > --enable-readline
> >
> > Hardware:
> > alexandria# camcontrol devlist
> > <SEAGATE ST318453LW 0006> at scbus0 target 0 lun 0 (da0,pass0)
> > <DELL PV-136T-FC 4194> at scbus2 target 129 lun 0 (pass1)
> > <DELL PV-136T 3.11> at scbus2 target 129 lun 1 (pass2,ch0)
> > <IBM ULTRIUM-TD2 37RH> at scbus2 target 129 lun 2 (pass3,sa0)
> > <IBM ULTRIUM-TD2 37RH> at scbus2 target 129 lun 3 (pass4,sa1)
> > <SEAGATE ST3146807FC 0006> at scbus2 target 130 lun 0 (pass5,da1)
> > alexandria#
>
> -------------------------------------------------------
> This SF.net email is sponsored by: Splunk Inc. Do you grep through log
> files for problems? Stop! Download the new AJAX search engine that makes
> searching your log files as easy as surfing the web. DOWNLOAD SPLUNK!
> http://ads.osdn.com/?ad_id=7637&alloc_id=16865&op=click
> _______________________________________________
> Bacula-users mailing list
> Bacula-users@lists.sourceforge.net
> https://lists.sourceforge.net/lists/listinfo/bacula-users
--
Best regards,
Kern
(">
/\
V_V
-------------------------------------------------------
This SF.net email is sponsored by: Splunk Inc. Do you grep through log files
for problems? Stop! Download the new AJAX search engine that makes
searching your log files as easy as surfing the web. DOWNLOAD SPLUNK!
http://ads.osdn.com/?ad_id=7637&alloc_id=16865&op=click
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users
