Daniel Pittman <[EMAIL PROTECTED]> writes:
> LHERBIER Lois <[EMAIL PROTECTED]> writes:

OK, after a suggestion to try the debugging output, I have the following
trace from doing the following:

*setdebug level=99 trace=1 director
Using default Catalog name=MyCatalog DB=bacula
*setdebug level=99 trace=1 storage
Automatically selected Storage: LTO-2
Connecting to Storage daemon LTO-2 at anu.rimspace.net:9103
3000 OK setdebug=99
*umount
Automatically selected Storage: LTO-2
3002 Device /dev/nst0 unmounted.
*label
Automatically selected Storage: LTO-2
Enter new Volume name: Daily01
Defined Pools:
     1: Default
     2: Daily
     3: Weekly
     4: Monthly
Select the Pool (1-4): 2
Connecting to Storage daemon LTO-2 at anu.rimspace.net:9103 ...
Sending label command for Volume "Daily01" Slot 0 ...
CatReq Job= UpdateMedia VolName=Daily01 VolJobs=0 VolFiles=1 VolBlocks=0 
VolBytes=0 VolMounts=0 VolErrors=0 VolWrites=0 MaxVolBytes=0 EndTime=1123747152 
VolStatus= Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=0

--- it hung at this stage ---

anu-dir: btimers.c:169 Start bsock timer 0x80d4d30 tid=0x41ca2bb0 for 600 secs 
at 1123747141
anu-dir: cram-md5.c:120 sending resp to challenge: n/+47l+a53/V+j/XO55wfA
anu-dir: cram-md5.c:52 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0
anu-dir: cram-md5.c:70 Authenticate OK 95ho+8/qP5/BX+1Mr8+uoA
anu-dir: btimers.c:183 Stop bsock timer 0x80d4d30 tid=0x41ca2bb0 at 1123747142.
anu-dir: btimers.c:169 Start bsock timer 0x80d4d30 tid=0x41ca2bb0 for 600 secs 
at 1123747145
/var/lib/bacula/bacula.traceanu-sd: cram-md5.c:52 send: auth cram-md5 <[EMAIL 
PROTECTED]> ssl=0
anu-dir: cram-md5.c:120 sending resp to challenge: B4wew1Imv68NukJ6Cy9eCC
anu-sd: cram-md5.c:70 Authenticate OK B4wew1Imv68NukJ6Cy9eCC
anu-dir: cram-md5.c:52 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0
anu-sd: cram-md5.c:120 sending resp to challenge: R4c9eGk375p7U5/bVz/fED
anu-dir: cram-md5.c:70 Authenticate OK R4c9eGk375p7U5/bVz/fED
anu-sd: btimers.c:183 Stop bsock timer 0x80aee30 tid=0x40aa0bb0 at 1123747146.
anu-sd: dircmd.c:179 Message channel init completed.
anu-dir: btimers.c:183 Stop bsock timer 0x80d4d30 tid=0x41ca2bb0 at 1123747146.
anu-sd: dircmd.c:471 Found device LTO-2
anu-sd: dircmd.c:645 Device not in use, unmounting
anu-sd: dev.c:374 rewind_dev /dev/nst0
anu-sd: dev.c:1394 Force close_dev /dev/nst0
anu-sd: dev.c:1342 really close_dev /dev/nst0
anu-dir: btimers.c:169 Start bsock timer 0x80d4d60 tid=0x41ca2bb0 for 600 secs 
at 1123747150
anu-sd: btimers.c:169 Start bsock timer 0x80aee30 tid=0x40aa0bb0 for 600 secs 
at 1123747150
anu-sd: cram-md5.c:52 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0
anu-dir: cram-md5.c:120 sending resp to challenge: szsDd6+9mU/bLG/JGh+8QA
anu-sd: cram-md5.c:70 Authenticate OK szsDd6+9mU/bLG/JGh+8QA
anu-dir: cram-md5.c:52 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0
anu-sd: cram-md5.c:120 sending resp to challenge: /+Y6nl/Y0y+wC1+Cfw+L6B
anu-dir: cram-md5.c:70 Authenticate OK /+Y6nl/Y0y+wC1+Cfw+L6B
anu-sd: btimers.c:183 Stop bsock timer 0x80aee30 tid=0x40aa0bb0 at 1123747150.
anu-sd: dircmd.c:179 Message channel init completed.
anu-dir: btimers.c:183 Stop bsock timer 0x80d4d60 tid=0x41ca2bb0 at 1123747150.
anu-sd: dircmd.c:471 Found device LTO-2
anu-sd: dev.c:255 open_dev: tape=1 dev_name=/dev/nst0 vol=Daily01
anu-sd: dev.c:260 open_dev: device is tape
anu-sd: dev.c:310 open_dev: tape 5 opened
anu-sd: dev.c:374 rewind_dev /dev/nst0
anu-sd: label.c:102 Big if statement in read_volume_label
anu-sd: label.c:107 Requested Volume "Daily01" on /dev/nst0 is not a Bacula 
labeled Volume, because: ERR=block.c:793 Read zero bytes at 0:0 on device 
/dev/nst0.
anu-sd: dev.c:374 rewind_dev /dev/nst0
anu-sd: label.c:398 write_volume_label()
anu-sd: label.c:350 Start create_volume_label()
anu-sd: dev.c:374 rewind_dev /dev/nst0
anu-sd: label.c:421 Wrote label of 152 bytes to /dev/nst0
anu-sd: label.c:424 Call write_block_to_dev()
anu-sd: dev.c:1200 weof_dev

A quick check confirms that the bacula-sd command is the only user of
device nst0, and it still has it open.

Unfortunately, this doesn't seem to show any activity at all regarding
the catalog update that was requested.  

Is there anything more I can do to debug this, or should I give up and
build from source, then attack it with a debugger?

      Daniel



-------------------------------------------------------
SF.Net email is Sponsored by the Better Software Conference & EXPO
September 19-22, 2005 * San Francisco, CA * Development Lifecycle Practices
Agile & Plan-Driven Development * Managing Projects & Teams * Testing & QA
Security * Process Improvement & Measurement * http://www.sqe.com/bsce5sf
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users

Reply via email to