Hello, Stephan Heine - [Genetic Interactive] wrote:
Let me get you the debug output from the job:
After looking through it I'm not sure how to interpret things: The SD is connected by the FD:
backer-sd: fd_cmds.c:118 Start run Job=OctaneDocs.2005-06-28_09.43.18 backer-sd: fd_cmds.c:135 <filed: append open session backer-sd: fd_cmds.c:211 Append open session: append open session backer-sd: fd_cmds.c:217 Append open session: /arch/backup/full/docs backer-sd: fd_cmds.c:222 >filed: 3000 OK open ticket = 1 backer-sd: fd_cmds.c:135 <filed: append data 1 backer-sd: fd_cmds.c:173 Append data: append data 1 backer-sd: fd_cmds.c:175 <bfiled: append data 1 backer-sd: append.c:51 Start append data. backer-sd: bnet.c:884 set network buffer size=32768
lateron, the director receives and handles meta data from the SD, so the SD obviously started receiving files from the FD:
backer-dir: msgchan.c:227 <stored: 3012 Job OctaneDocs.2005-06-28_09.43.18 jobstatus 82 backer-dir: getmsg.c:78 bget_dirmsg 231: UpdCat Job=OctaneDocs.2005-06-28_09.43.18 FileAttributes backer-dir: jcr.c:499 Inc get_jcr 0x99f67f8 use_count=3 backer-dir: getmsg.c:138 Getmsg got jcr 0x99f67f8 backer-dir: getmsg.c:183 Catalog upd jcr 0x99f67f8: UpdCat Job=OctaneDocs.2005-06-28_09.43.18 FileAttributes backer-dir: catreq.c:320 UpdCat msg=UpdCat Job=OctaneDocs.2005-06-28_09.43.18 FileAttributes backer-dir: catreq.c:322 UpdCat VolSessId=1 VolSessT=1119944505 FI=1 Strm=5 data_len=154 backer-dir: catreq.c:333 dird<stored: stream=5 C:/Documents and Settings/Shell/My Documents/desktop.ini backer-dir: catreq.c:334 dird<stored: attr=A A IP4 B A A A BM BAA B BCwPoT BCfwsD BCfwr+ A A E backer-dir: catreq.c:342 dird<filed: stream=5 C:/Documents and Settings/Shell/My Documents/desktop.ini backer-dir: catreq.c:343 dird<filed: attr=A A IP4 B A A A BM BAA B BCwPoT BCfwsD BCfwr+ A A E backer-dir: sql_create.c:504 Fname=C:/Documents and Settings/Shell/My Documents/desktop.ini backer-dir: sql_create.c:505 put_file_into_catalog backer-dir: sql.c:397 split path=C:/Documents and Settings/Shell/My Documents/ file=desktop.ini backer-dir: sql_create.c:522 db_create_filename_record: desktop.ini backer-dir: sql_create.c:528 db_create_path_record: C:/Documents and Settings/Shell/My Documents/ backer-dir: sql_create.c:534 db_create_file_record OK backer-dir: sql_create.c:536 CreateAttributes Path=C:/Documents and Settings/Shell/My Documents/ File=desktop.ini FilenameId=1
Lateron, the SD does some volume handling (which I don't understand, because it already uses that volume...), and afterwards the connection from the FD seems to time out.
backer-sd: dev.c:374 rewind_dev /arch/backup/full/docs backer-sd: label.c:163 Compare Vol names: VolName=octanedocsfull_0001 hdr=octanedocsfull_0001 backer-sd: label.c:177 Copy vol_name=octanedocsfull_0001 Volume Label: Id : Bacula 1.0 immortal VerNo : 11 VolName : octanedocsfull_0001 PrevVolName : VolFile : 0 LabelType : VOL_LABEL LabelSize : 177 PoolName : OctaneDocsFull MediaType : File PoolType : Backup HostName : backer Date label written: 24-Jun-2005 08:38 backer-sd: label.c:182 Leave read_volume_label() VOL_OK backer-sd: mount.c:185 dirVol=octanedocsfull_0001 dirStat=Append backer-sd: mount.c:192 Vol OK name=octanedocsfull_0001 backer-sd: mount.c:319 Device previously written, moving to end of data backer-sd: dev.c:436 eod_dev backer-sd: mount.c:346 update volinfo mounts=7 backer-sd: askdir.c:229 Update cat VolFiles=0 backer-sd: askdir.c:247 update_volume_info(): CatReq Job=OctaneDocs.2005-06-28_09.43.18 UpdateMedia VolName=octanedocsfull_0001 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=7 VolErrors=0 VolWrites=1 MaxVolBytes=0 EndTime=1119944603 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=0 backer-sd: askdir.c:91 Get vol info=1000 OK VolName=octanedocsfull_0001 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=7 VolErrors=0 VolWrites=1 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=0 EndFile=0 EndBlock=0 backer-sd: askdir.c:112 do_reqest_vol_info got slot=0 Volume=octanedocsfull_0001 backer-sd: askdir.c:255 get_volume_info(): 1000 OK VolName=octanedocsfull_0001 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=7 VolErrors=0 VolWrites=1 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=0 EndFile=0 EndBlock=0 backer-sd: mount.c:354 set APPEND, normal return from read_dev_for_append backer-sd: device.c:427 unblock 3 from acquire.c:369 backer-sd: append.c:77 Begin append device=/arch/backup/full/docs backer-sd: append.c:82 Just after acquire_device_for_append backer-sd: mem_pool.c:111 sm_get_pool_memory reuse 8b43dd8 to record.c:154 backer-sd: label.c:512 session_label record=8b43d78 backer-sd: label.c:568 Write sesson_label record JobId=4966 FI=SOS_LABEL SessId=1 Strm=4966 len=166 remainder=0 backer-sd: record.c:173 Enter free_record. backer-sd: mem_pool.c:221 free_pool_memory 8b43dd8 pool=3 from record.c:175 backer-sd: record.c:177 Data buf is freed. backer-sd: mem_pool.c:221 free_pool_memory 8b43d68 pool=0 from record.c:178 backer-sd: record.c:179 Leave free_record. backer-sd: label.c:572 Leave write_session_label Block=0 File=0 backer-sd: append.c:234 Enter bnet_get backer-sd: bget_msg.c:63 Got BNET_EOD backer-sd: append.c:236 End read loop with FD. Stat=-1 backer-sd: append.c:234 Enter bnet_get backer-sd: bget_msg.c:63 Got BNET_EOD backer-sd: append.c:236 End read loop with FD. Stat=-1 backer-sd: append.c:234 Enter bnet_get backer-sd: bget_msg.c:63 Got BNET_EOD backer-sd: append.c:236 End read loop with FD. Stat=-1 backer-sd: append.c:234 Enter bnet_get backer-sd: bget_msg.c:63 Got BNET_EOD backer-sd: append.c:236 End read loop with FD. Stat=-1 backer-sd: append.c:234 Enter bnet_get backer-sd: bget_msg.c:63 Got BNET_EOD backer-sd: append.c:236 End read loop with FD. Stat=-1 backer-sd: append.c:234 Enter bnet_get backer-sd: bget_msg.c:63 Got BNET_EOD backer-sd: append.c:236 End read loop with FD. Stat=-1 backer-sd: append.c:234 Enter bnet_get backer-sd: bget_msg.c:63 Got BNET_EOD backer-sd: append.c:236 End read loop with FD. Stat=-1 backer-sd: append.c:234 Enter bnet_get backer-sd: bget_msg.c:63 Got BNET_EOD backer-sd: append.c:236 End read loop with FD. Stat=-1 backer-sd: workq.c:324 timedwait=110 backer-sd: workq.c:371 Check for work request backer-sd: workq.c:375 wq->first==NULL = 1 backer-sd: workq.c:376 timedout=1 backer-sd: workq.c:378 break big loop
Now, unfortunately the debug output has no timestamps, but the only thing I can imagine is that the SD needs some time in between and the FD or some router in between then times out the connection.
Have you tried the Heratbeat interval setting? Arno -- IT-Service Lehmann [EMAIL PROTECTED] Arno Lehmann http://www.its-lehmann.de ------------------------------------------------------- SF.Net email is sponsored by: Discover Easy Linux Migration Strategies from IBM. Find simple to follow Roadmaps, straightforward articles, informative Webcasts and more! Get everything you need to get up to speed, fast. http://ads.osdn.com/?ad_id=7477&alloc_id=16492&op=click _______________________________________________ Bacula-users mailing list Bacula-users@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/bacula-users
