version 2.2.4 patched from sourceforge Linux kernel 2.6.x I am running 10+ FD's, one SD, and one Director. I am having problems with one of my FD's, the others are fine. Not sure if it makes any difference, but the FD is on the same machine as the Director.
I have no issues with the network, I see no errors on either the interface of the FD or the SD. All FD's are plugged into the same netgear switch. The SD is plugged into a different netgear switch which is then plugged into the FD's switch. I run a backup job (or via schedule) and the amount/size/volume of data is transfered each time, and then everything stops/hangs/does nothing. ls -l /var/data/bacula/spool/backupserver-sd.data.472.fileserver-backup.2007-10-22_18.54.33.DLT-V4.spool -rw-r----- 1 root bacula 2193816 Oct 22 18:56 A short while later, I will get a console message 22-Oct 18:56 backupserver-sd: 3301 Issuing autochanger "loaded? drive 0" command. 22-Oct 18:56 backupserver-sd: 3302 Autochanger "loaded? drive 0", result is Slot 3. 22-Oct 18:56 backupserver-sd: Volume "CNI906" previously written, moving to end of data. 22-Oct 18:56 backupserver-sd: Ready to append to end of Volume "CNI906" at file=1. 22-Oct 18:56 backupserver-sd: Spooling data ... 22-Oct 18:56 fileserver-fd: fileserver-backup.2007-10-22_18.54.33 Fatal error: backup.c:892 Network send error to SD. ERR=Success I know it says "Network send error", however, I have checked the network, and can not find a problem with any of the equipment. I have run the fd and sd with debug options to provide additional output, I hope this helps. If any other information would help in diagnosis, please just ask for it. /usr/local/sbin/bacula-fd -f -s -d 200 -u root -g bacula -c /etc/bacula/bacula-fd.conf /home/spencer/bacula-sd -f -d 200 -s -u root -g bacula -c /etc/bacula/bacula-sd.conf cat /root/bacula-fd.log bacula-fd: filed_conf.c:438 Inserting director res: fileserver-mon fileserver-fd: jcr.c:132 read_last_jobs seek to 188 fileserver-fd: jcr.c:139 Read num_items=10 fileserver-fd: pythonlib.c:113 No script dir. prog=FDStartUp fileserver-fd: filed.c:225 filed: listening on port 9102 fileserver-fd: bnet_server.c:96 Addresses host[ipv4:0.0.0.0:9102] fileserver-fd: bnet.c:666 who=client host=192.168.1.30 port=36387 fileserver-fd: jcr.c:602 OnEntry JobStatus=fileserver-fd: jcr.c:622 OnExit JobStatus=C set=C fileserver-fd: find.c:81 init_find_files ff=8094e60 fileserver-fd: job.c:233 <dird: Hello Director fileserver-dir calling fileserver-fd: job.c:249 Executing Hello command. fileserver-fd: job.c:353 Calling Authenticate fileserver-fd: cram-md5.c:71 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 fileserver-fd: cram-md5.c:131 cram-get: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 fileserver-fd: cram-md5.c:150 sending resp to challenge: 6U+ZK4lCcB/uXh+k+X/qdB fileserver-fd: job.c:357 OK Authenticate fileserver-fd: job.c:233 <dird: JobId=0 Job=-Console-.2007-10-22_18.53.31 SDid=0 SDtime=0 Authorization=dummy fileserver-fd: job.c:249 Executing JobId= command. fileserver-fd: job.c:451 JobId=0 Auth=dummy fileserver-fd: job.c:233 <dird: statusfileserver-fd: job.c:249 Executing status command. fileserver-fd: runscript.c:102 runscript: running all RUNSCRIPT object (ClientAfterJob) JobStatus=C fileserver-fd: pythonlib.c:237 No startup module. fileserver-fd: job.c:337 Calling term_find_files fileserver-fd: job.c:340 Done with term_find_files fileserver-fd: mem_pool.c:377 garbage collect memory pool fileserver-fd: job.c:342 Done with free_jcr fileserver-fd: bnet.c:666 who=client host=192.168.1.30 port=36387 fileserver-fd: jcr.c:602 OnEntry JobStatus=fileserver-fd: jcr.c:622 OnExit JobStatus=C set=C fileserver-fd: find.c:81 init_find_files ff=8094e60 fileserver-fd: job.c:233 <dird: Hello Director fileserver-dir calling fileserver-fd: job.c:249 Executing Hello command. fileserver-fd: job.c:353 Calling Authenticate fileserver-fd: cram-md5.c:71 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 fileserver-fd: cram-md5.c:131 cram-get: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 fileserver-fd: cram-md5.c:150 sending resp to challenge: /2gP/C+Fx+ZhT98YS4+hzD fileserver-fd: job.c:357 OK Authenticate fileserver-fd: job.c:233 <dird: JobId=472 Job=fileserver-backup.2007-10-22_18.54.33 SDid=1 SDtime=1193079020 Authorization=JMKD-GKGP-LMNP-ODNP-EDME-NJBC-LEMK-BPGJ fileserver-fd: job.c:249 Executing JobId= command. fileserver-fd: job.c:451 JobId=472 Auth=JMKD-GKGP-LMNP-ODNP-EDME-NJBC-LEMK-BPGJ fileserver-fd: job.c:233 <dird: fileset vss=1 fileserver-fd: job.c:249 Executing fileset command. fileserver-fd: job.c:688 I fileserver-fd: job.c:688 O M0 fileserver-fd: job.c:688 N fileserver-fd: job.c:688 F /boot fileserver-fd: job.c:688 F /etc fileserver-fd: job.c:688 F /usr/local fileserver-fd: job.c:688 F /var/lib fileserver-fd: job.c:688 F /var/data/svn fileserver-fd: job.c:688 F /var/data/mysql-bak fileserver-fd: job.c:688 F /var/spool/cyrus fileserver-fd: job.c:688 F /usr/src fileserver-fd: job.c:688 F /var/mail fileserver-fd: job.c:688 F /home/zak/mail fileserver-fd: job.c:688 F /home/james/mail fileserver-fd: job.c:688 F /home/dpar2/mail fileserver-fd: job.c:688 N fileserver-fd: job.c:688 E fileserver-fd: job.c:688 F /tmp fileserver-fd: job.c:688 N fileserver-fd: job.c:233 <dird: level = incremental mtime_only=0 fileserver-fd: job.c:249 Executing level = command. fileserver-fd: job.c:1160 level_cmd: level = incremental mtime_only=0 fileserver-fd: job.c:233 <dird: level = since_utime 1192958180 mtime_only=0 fileserver-fd: job.c:249 Executing level = command. fileserver-fd: job.c:1160 level_cmd: level = since_utime 1192958180 mtime_only=0 fileserver-fd: job.c:1194 since_time=1192958180 fileserver-fd: job.c:1215 Dirtime=1193079276315533 FDtime=1193079276315510 fileserver-fd: job.c:1217 rt=56 adj=18446744073709551565 fileserver-fd: job.c:1215 Dirtime=1193079276315628 FDtime=1193079276315603 fileserver-fd: job.c:1217 rt=59 adj=18446744073709551511 fileserver-fd: job.c:1215 Dirtime=1193079276315776 FDtime=1193079276315747 fileserver-fd: job.c:1217 rt=64 adj=18446744073709551450 fileserver-fd: job.c:1215 Dirtime=1193079276315872 FDtime=1193079276315847 fileserver-fd: job.c:1217 rt=58 adj=18446744073709551396 fileserver-fd: job.c:1215 Dirtime=1193079276315964 FDtime=1193079276315940 fileserver-fd: job.c:1217 rt=58 adj=18446744073709551343 fileserver-fd: job.c:1215 Dirtime=1193079276316057 FDtime=1193079276316032 fileserver-fd: job.c:1217 rt=59 adj=18446744073709551289 fileserver-fd: job.c:1215 Dirtime=1193079276316149 FDtime=1193079276316125 fileserver-fd: job.c:1217 rt=58 adj=18446744073709551236 fileserver-fd: job.c:1215 Dirtime=1193079276316242 FDtime=1193079276316218 fileserver-fd: job.c:1217 rt=58 adj=18446744073709551183 fileserver-fd: job.c:1221 rt=58 adj=18446744073709551562 fileserver-fd: job.c:1236 adj = 0 since_time=1192958180 fileserver-fd: job.c:233 <dird: storage address=backupserver.cluster.local port=9103 ssl=0 fileserver-fd: job.c:249 Executing storage command. fileserver-fd: job.c:1291 StorageCmd: storage address=backupserver.cluster.local port=9103 ssl=0 fileserver-fd: job.c:1297 Open storage: backupserver.cluster.local:9103 ssl=0 fileserver-fd: bsock.c:195 Current host[ipv4:192.168.1.135:9103] All host[ipv4:192.168.1.135:9103] fileserver-fd: bsock.c:149 who=Storage daemon host=backupserver.cluster.local port=9103 fileserver-fd: job.c:1309 Connection OK to SD. fileserver-fd: cram-md5.c:131 cram-get: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 fileserver-fd: cram-md5.c:150 sending resp to challenge: XB+VsUQJ44JUUT+eURwxEB fileserver-fd: cram-md5.c:78 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 fileserver-fd: cram-md5.c:97 Authenticate OK 94ldXX/Ic5FIJUAeTm/qKA fileserver-fd: job.c:1318 Authenticated with SD. fileserver-fd: job.c:233 <dird: RunBeforeJob /etc/bacula/scripts/makemysqlbackup fileserver-fd: job.c:249 Executing RunBeforeJob command. fileserver-fd: job.c:462 runbefore_cmd: RunBeforeJob /etc/bacula/scripts/makemysqlbackup fileserver-fd: runscript.c:204 runscript: running a RUNSCRIPT object fileserver-fd: util.c:580 edit_job_codes: /etc/bacula/scripts/makemysqlbackup fileserver-fd: runscript.c:211 runscript: running '/etc/bacula/scripts/makemysqlbackup'... fileserver-fd: runscript.c:236 runscript OK fileserver-fd: job.c:233 <dird: backup fileserver-fd: job.c:249 Executing backup command. fileserver-fd: jcr.c:602 OnEntry JobStatus=C set=B fileserver-fd: jcr.c:622 OnExit JobStatus=B set=B fileserver-fd: job.c:1350 begin backup ff=8094e60 fileserver-fd: job.c:1358 bfiled>dird: 2000 OK backup fileserver-fd: job.c:1364 >stored: append open session fileserver-fd: job.c:1369 <stored: 3000 OK open ticket = 1 fileserver-fd: job.c:1374 Got Ticket=1 fileserver-fd: job.c:1384 >stored: append data 1 fileserver-fd: job.c:1389 <stored: append data 1 fileserver-fd: job.c:1798 3000 OK data fileserver-fd: pythonlib.c:237 No startup module. fileserver-fd: job.c:1436 begin blast ff=8094e60 fileserver-fd: jcr.c:602 OnEntry JobStatus=B set=R fileserver-fd: jcr.c:622 OnExit JobStatus=R set=R fileserver-fd: find.c:93 Enter set_find_options() fileserver-fd: find.c:96 Leave set_find_options() fileserver-fd: find.c:198 F /boot fileserver-fd: backup.c:278 FT_DIREND: /boot/lost+found/ fileserver-fd: backup.c:332 bfiled: sending /boot/lost+found to stored fileserver-fd: backup.c:1137 No strip for /boot/lost+found fileserver-fd: backup.c:245 FT_REG saving: /boot/map fileserver-fd: backup.c:332 bfiled: sending /boot/map to stored fileserver-fd: backup.c:1137 No strip for /boot/map fileserver-fd: backup.c:895 Send data to SD len=80384 fileserver-fd: backup.c:245 FT_REG saving: /boot/config-2.4.30 fileserver-fd: backup.c:332 bfiled: sending /boot/config-2.4.30 to stored fileserver-fd: backup.c:1137 No strip for /boot/config-2.4.30 fileserver-fd: backup.c:895 Send data to SD len=23184 fileserver-fd: backup.c:245 FT_REG saving: /boot/config-2.4.32 fileserver-fd: backup.c:332 bfiled: sending /boot/config-2.4.32 to stored fileserver-fd: backup.c:1137 No strip for /boot/config-2.4.32 fileserver-fd: backup.c:895 Send data to SD len=23938 fileserver-fd: backup.c:245 FT_REG saving: /boot/System.map-2.4.30 fileserver-fd: backup.c:332 bfiled: sending /boot/System.map-2.4.30 to stored fileserver-fd: backup.c:1137 No strip for /boot/System.map-2.4.30 fileserver-fd: backup.c:895 Send data to SD len=647691 fileserver-fd: backup.c:245 FT_REG saving: /boot/System.map-2.4.32 fileserver-fd: backup.c:332 bfiled: sending /boot/System.map-2.4.32 to stored fileserver-fd: backup.c:1137 No strip for /boot/System.map-2.4.32 fileserver-fd: backup.c:895 Send data to SD len=647760 fileserver-fd: backup.c:245 FT_REG saving: /boot/System.map-2.6.16.1-2 fileserver-fd: backup.c:332 bfiled: sending /boot/System.map-2.6.16.1-2 to stored fileserver-fd: backup.c:1137 No strip for /boot/System.map-2.6.16.1-2 fileserver-fd: backup.c:895 Send data to SD len=742070 fileserver-fd: backup.c:245 FT_REG saving: /boot/config-2.6.16.1-2 fileserver-fd: backup.c:332 bfiled: sending /boot/config-2.6.16.1-2 to stored fileserver-fd: backup.c:1137 No strip for /boot/config-2.6.16.1-2 fileserver-fd: backup.c:895 Send data to SD len=31119 fileserver-fd: backup.c:245 FT_REG saving: /boot/vmlinuz-2.6.16.1-2 fileserver-fd: backup.c:332 bfiled: sending /boot/vmlinuz-2.6.16.1-2 to stored fileserver-fd: backup.c:1137 No strip for /boot/vmlinuz-2.6.16.1-2 fileserver-fd: jcr.c:602 OnEntry JobStatus=R set=f fileserver-fd: jcr.c:622 OnExit JobStatus=f set=f fileserver-fd: jcr.c:602 OnEntry JobStatus=f set=E fileserver-fd: jcr.c:622 OnExit JobStatus=f set=E fileserver-fd: backup.c:197 end blast_data ok=0 fileserver-fd: jcr.c:602 OnEntry JobStatus=f set=E fileserver-fd: jcr.c:622 OnExit JobStatus=f set=E fileserver-fd: bnet.c:666 who=client host=192.168.1.30 port=36387 fileserver-fd: jcr.c:602 OnEntry JobStatus=fileserver-fd: jcr.c:622 OnExit JobStatus=C set=C fileserver-fd: find.c:81 init_find_files ff=8097838 fileserver-fd: job.c:233 <dird: Hello Director fileserver-dir calling fileserver-fd: job.c:249 Executing Hello command. fileserver-fd: job.c:353 Calling Authenticate fileserver-fd: cram-md5.c:71 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 fileserver-fd: cram-md5.c:131 cram-get: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 fileserver-fd: cram-md5.c:150 sending resp to challenge: k9tCKB+g+zpaQT/Aq6NH6D fileserver-fd: job.c:357 OK Authenticate fileserver-fd: job.c:233 <dird: JobId=0 Job=-Console-.2007-10-22_18.53.31 SDid=0 SDtime=0 Authorization=dummy fileserver-fd: job.c:249 Executing JobId= command. fileserver-fd: job.c:451 JobId=0 Auth=dummy fileserver-fd: job.c:233 <dird: statusfileserver-fd: job.c:249 Executing status command. fileserver-fd: runscript.c:102 runscript: running all RUNSCRIPT object (ClientAfterJob) JobStatus=C fileserver-fd: pythonlib.c:237 No startup module. fileserver-fd: job.c:337 Calling term_find_files fileserver-fd: job.c:340 Done with term_find_files fileserver-fd: mem_pool.c:377 garbage collect memory pool fileserver-fd: job.c:342 Done with free_jcr [EMAIL PROTECTED]:/var/log/bacula# cat /root/bacula-sd.log bacula-sd: stored_conf.c:675 Inserting director res: backupserver-mon backupserver-sd: jcr.c:132 read_last_jobs seek to 188 backupserver-sd: jcr.c:139 Read num_items=10 backupserver-sd: bpipe.c:371 Run program returning 0 backupserver-sd: pythonlib.c:113 No script dir. prog=SDStartUp backupserver-sd: jcr.c:602 OnEntry JobStatus=backupserver-sd: jcr.c:622 OnExit JobStatus=C set=C backupserver-sd: stored.c:478 calling init_dev /dev/nst0 backupserver-sd: dev.c:250 init_dev: tape=1 dev_name=/dev/nst0 backupserver-sd: stored.c:480 SD init done /dev/nst0 backupserver-sd: autochanger.c:299 Locking changer Autochanger backupserver-sd: autochanger.c:269 Run program=/usr/libexec/bacula/mtx-changer /dev/sg1 loaded 0 /dev/nst0 0 backupserver-sd: bnet_server.c:96 Addresses host[ipv4:0.0.0.0:9103] backupserver-sd: autochanger.c:271 run_prog: /usr/libexec/bacula/mtx-changer /dev/sg1 loaded 0 /dev/nst0 0 stat=0 result=3 backupserver-sd: autochanger.c:308 Unlocking changer Autochanger backupserver-sd: stored.c:493 calling first_open_device "DLT-V4" (/dev/nst0) backupserver-sd: device.c:275 start open_output_device() backupserver-sd: device.c:294 Opening device. backupserver-sd: dev.c:292 open dev: type=2 dev_name="DLT-V4" (/dev/nst0) vol= mode=OPEN_READ_ONLY backupserver-sd: dev.c:342 Open dev: device is tape backupserver-sd: autochanger.c:299 Locking changer Autochanger backupserver-sd: autochanger.c:269 Run program=/usr/libexec/bacula/mtx-changer /dev/sg1 loaded 0 /dev/nst0 0 backupserver-sd: autochanger.c:271 run_prog: /usr/libexec/bacula/mtx-changer /dev/sg1 loaded 0 /dev/nst0 0 stat=0 result=3 backupserver-sd: autochanger.c:308 Unlocking changer Autochanger backupserver-sd: dev.c:357 Try open "DLT-V4" (/dev/nst0) mode=OPEN_READ_ONLY backupserver-sd: dev.c:379 Rewind after open backupserver-sd: dev.c:2360 In set_os_device_parameters backupserver-sd: dev.c:2366 Set block size to zero backupserver-sd: dev.c:431 open dev: tape 4 opened backupserver-sd: dev.c:306 preserve=0x0 fd=4 backupserver-sd: device.c:300 open dev "DLT-V4" (/dev/nst0) OK backupserver-sd: label.c:81 Enter read_volume_label res=0 device="DLT-V4" (/dev/nst0) vol= dev_Vol=*NULL* backupserver-sd: label.c:149 Big if statement in read_volume_label backupserver-sd: label.c:820 unser_vol_label Volume Label: Id : Bacula 1.0 immortal VerNo : 11 VolName : CNI906 PrevVolName : VolFile : 0 LabelType : VOL_LABEL LabelSize : 166 PoolName : Daily MediaType : DLT-V4 PoolType : Backup HostName : backupserver Date label written: 02-Oct-2007 14:06 backupserver-sd: reserve.c:313 jid=0 reserve_volume CNI906 backupserver-sd: reserve.c:238 jid=0 new Vol=CNI906 at 80c9f98 dev="DLT-V4" (/dev/nst0) backupserver-sd: reserve.c:181 jid=0 List from end new volume: CNI906 at 80c9f98 on device "DLT-V4" (/dev/nst0) backupserver-sd: label.c:223 Compare Vol names: VolName= hdr=CNI906 backupserver-sd: label.c:239 Copy vol_name=CNI906 Volume Label: Id : Bacula 1.0 immortal VerNo : 11 VolName : CNI906 PrevVolName : VolFile : 0 LabelType : VOL_LABEL LabelSize : 166 PoolName : Daily MediaType : DLT-V4 PoolType : Backup HostName : backupserver Date label written: 02-Oct-2007 14:06 backupserver-sd: label.c:244 Leave read_volume_label() VOL_OK backupserver-sd: mem_pool.c:377 garbage collect memory pool backupserver-sd: bnet.c:666 who=client host=192.168.1.30 port=36643 backupserver-sd: dircmd.c:171 Conn: Hello Director fileserver-dir calling backupserver-sd: dircmd.c:181 Got a DIR connection backupserver-sd: jcr.c:602 OnEntry JobStatus=backupserver-sd: jcr.c:622 OnExit JobStatus=C set=C backupserver-sd: cram-md5.c:71 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 backupserver-sd: cram-md5.c:131 cram-get: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 backupserver-sd: cram-md5.c:150 sending resp to challenge: S7o0ej/Ie5/Jb7+G2zwyLB backupserver-sd: dircmd.c:202 Message channel init completed. backupserver-sd: dircmd.c:209 <dird: status backupserver-sd: dircmd.c:223 Do command: status backupserver-sd: pythonlib.c:237 No startup module. backupserver-sd: mem_pool.c:377 garbage collect memory pool backupserver-sd: bnet.c:666 who=client host=192.168.1.30 port=36643 backupserver-sd: dircmd.c:171 Conn: Hello Director fileserver-dir calling backupserver-sd: dircmd.c:181 Got a DIR connection backupserver-sd: jcr.c:602 OnEntry JobStatus=backupserver-sd: jcr.c:622 OnExit JobStatus=C set=C backupserver-sd: cram-md5.c:71 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 backupserver-sd: cram-md5.c:131 cram-get: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 backupserver-sd: cram-md5.c:150 sending resp to challenge: TV/iZh/UcG/eQUM2tSonZB backupserver-sd: dircmd.c:202 Message channel init completed. backupserver-sd: dircmd.c:209 <dird: JobId=472 job=fileserver-backup.2007-10-22_18.54.33 job_name=fileserver-backup client_name=fileserver-fd type=66 level=73 FileSet=FileserverFullSet NoAttr=0 SpoolAttr=1 FileSetMD5=q6YQC8Qd2l/zE9xBYx9rsC SpoolData=1 WritePartAfterJob=1 PreferMountedVols=1 backupserver-sd: dircmd.c:223 Do command: JobId= backupserver-sd: job.c:87 <dird: JobId=472 job=fileserver-backup.2007-10-22_18.54.33 job_name=fileserver-backup client_name=fileserver-fd type=66 level=73 FileSet=FileserverFullSet NoAttr=0 SpoolAttr=1 FileSetMD5=q6YQC8Qd2l/zE9xBYx9rsC SpoolData=1 WritePartAfterJob=1 PreferMountedVols=1 backupserver-sd: job.c:141 >dird jid=472: 3000 OK Job SDid=1 SDtime=1193079020 Authorization=JMKD-GKGP-LMNP-ODNP-EDME-NJBC-LEMK-BPGJ backupserver-sd: pythonlib.c:237 No startup module. backupserver-sd: dircmd.c:209 <dird: use storage=Tape media_type=DLT-V4 pool_name=Daily pool_type=Backup append=1 copy=0 stripe=0 backupserver-sd: dircmd.c:223 Do command: use storage= backupserver-sd: reserve.c:586 jid=472 <dird: use storage=Tape media_type=DLT-V4 pool_name=Daily pool_type=Backup append=1 copy=0 stripe=0 backupserver-sd: reserve.c:615 jid=472 <dird device: use device=DLT-V4 backupserver-sd: reserve.c:632 jid=472 Storage=Tape media_type=DLT-V4 pool=Daily pool_type=Backup append=1 backupserver-sd: reserve.c:634 jid=472 Device=DLT-V4 backupserver-sd: reserve.c:715 jid=472 PrefMnt=1 exact=1 suitable=0 chgronly=0 any=0 backupserver-sd: reserve.c:828 jid=472 PrefMnt=1 exact=1 suitable=0 chgronly=0 backupserver-sd: reserve.c:850 jid=472 duplicate vol list backupserver-sd: reserve.c:869 jid=472 look for vol in vol list backupserver-sd: askdir.c:229 >dird: CatReq Job=fileserver-backup.2007-10-22_18.54.33 GetVolInfo VolName=CNI906 write=1 backupserver-sd: askdir.c:182 <dird 1000 OK VolName=CNI906 VolJobs=15 VolFiles=1 VolBlocks=6684 VolBytes=431262720 VolMounts=6 VolErrors=0 VolWrites=6686 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Append Slot=3 MaxVolJobs=0 MaxVolFiles=0 InChanger=1 VolReadTime=0 VolWriteTime=4716850 EndFile=0 EndBlock=6684 VolParts=0 LabelType=0 MediaId=11 backupserver-sd: askdir.c:204 do_reqest_vol_info return true slot=3 Volume=CNI906 backupserver-sd: reserve.c:881 jid=472 vol=CNI906 OK for this job backupserver-sd: reserve.c:892 jid=472 vol=CNI906 is in changer backupserver-sd: reserve.c:805 jid=472 Incorrect changer device Autochanger backupserver-sd: reserve.c:515 jid=472 free vol_list Volume=CNI906 dev="DLT-V4" (/dev/nst0) backupserver-sd: reserve.c:936 jid=472 deleted temp vol list backupserver-sd: reserve.c:986 jid=472 search res for DLT-V4 backupserver-sd: reserve.c:989 jid=472 Try match changer res=Autochanger backupserver-sd: reserve.c:1018 jid=472 Try match res=DLT-V4 backupserver-sd: reserve.c:1058 jid=472 chk MediaType device=DLT-V4 request=DLT-V4 backupserver-sd: reserve.c:1081 jid=472 try reserve DLT-V4 backupserver-sd: reserve.c:1095 jid=472 have_vol=0 vol= backupserver-sd: reserve.c:1254 jid=472 reserve_append device is "DLT-V4" (/dev/nst0) backupserver-sd: reserve.c:1286 jid=472 PrefMnt=1 exact=1 suitable=1 chgronly=0 any=0 backupserver-sd: reserve.c:1411 jid=472 OK Dev avail reserved "DLT-V4" (/dev/nst0) backupserver-sd: reserve.c:1264 jid=472 Inc reserve=1 dev="DLT-V4" (/dev/nst0) 80b8f38 backupserver-sd: reserve.c:1105 jid=472 Reserved=1 dev_name=DLT-V4 mediatype=DLT-V4 pool=Daily ok=1 backupserver-sd: askdir.c:256 dir_find_next_appendable_volume backupserver-sd: askdir.c:271 >dird: CatReq Job=fileserver-backup.2007-10-22_18.54.33 FindMedia=1 pool_name=Daily media_type=DLT-V4 backupserver-sd: askdir.c:182 <dird 1000 OK VolName=CNI906 VolJobs=15 VolFiles=1 VolBlocks=6684 VolBytes=431262720 VolMounts=6 VolErrors=0 VolWrites=6686 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Append Slot=3 MaxVolJobs=0 MaxVolFiles=0 InChanger=1 VolReadTime=0 VolWriteTime=4716850 EndFile=0 EndBlock=6684 VolParts=0 LabelType=0 MediaId=11 backupserver-sd: askdir.c:204 do_reqest_vol_info return true slot=3 Volume=CNI906 backupserver-sd: reserve.c:406 jid=472 find_vol=CNI906 found=1 backupserver-sd: reserve.c:181 jid=472 List from find_volume: CNI906 at 80c9f98 on device "DLT-V4" (/dev/nst0) backupserver-sd: reserve.c:538 jid=472 Vol=CNI906 on same dev. backupserver-sd: reserve.c:313 jid=472 reserve_volume CNI906 backupserver-sd: reserve.c:181 jid=472 List from begin reserve_volume: CNI906 at 80c9f98 on device "DLT-V4" (/dev/nst0) backupserver-sd: reserve.c:181 jid=472 List from end new volume: CNI906 at 80c9f98 on device "DLT-V4" (/dev/nst0) backupserver-sd: reserve.c:1110 jid=472 looking for Volume=CNI906 backupserver-sd: reserve.c:1148 jid=472 >dird changer: 3000 OK use device device=DLT-V4 backupserver-sd: reserve.c:1029 jid=472 Device DLT-V4 reserved=1 for append. backupserver-sd: reserve.c:959 jid=472 available device found=DLT-V4 backupserver-sd: dircmd.c:209 <dird: run backupserver-sd: dircmd.c:223 Do command: run backupserver-sd: job.c:156 Run_cmd: run backupserver-sd: jcr.c:602 OnEntry JobStatus=C set=F backupserver-sd: jcr.c:622 OnExit JobStatus=F set=F backupserver-sd: job.c:175 fileserver-backup.2007-10-22_18.54.33 waiting 1800 sec for FD to contact SD key=JMKD-GKGP-LMNP-ODNP-EDME-NJBC-LEMK-BPGJ backupserver-sd: bnet.c:666 who=client host=192.168.1.30 port=36643 backupserver-sd: dircmd.c:171 Conn: Hello Start Job fileserver-backup.2007-10-22_18.54.33 backupserver-sd: dircmd.c:173 Got a FD connection backupserver-sd: job.c:218 Found Job fileserver-backup.2007-10-22_18.54.33 backupserver-sd: cram-md5.c:71 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 backupserver-sd: cram-md5.c:131 cram-get: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 backupserver-sd: cram-md5.c:150 sending resp to challenge: 94ldXX/Ic5FIJUAeTm/qKA backupserver-sd: job.c:235 OK Authentication jid=472 Job fileserver-backup.2007-10-22_18.54.33 backupserver-sd: job.c:194 Running job fileserver-backup.2007-10-22_18.54.33 backupserver-sd: fd_cmds.c:122 Start run Job=fileserver-backup.2007-10-22_18.54.33 backupserver-sd: jcr.c:602 OnEntry JobStatus=F set=R backupserver-sd: jcr.c:622 OnExit JobStatus=R set=R backupserver-sd: fd_cmds.c:160 <filed: append open session backupserver-sd: fd_cmds.c:228 Append open session: append open session backupserver-sd: fd_cmds.c:238 >filed: 3000 OK open ticket = 1 backupserver-sd: fd_cmds.c:160 <filed: append data 1 backupserver-sd: fd_cmds.c:190 Append data: append data 1 backupserver-sd: fd_cmds.c:192 <bfiled: append data 1 backupserver-sd: append.c:73 Start append data. res=1 backupserver-sd: acquire.c:329 acquire_append device is tape backupserver-sd: acquire.c:418 Not in append mode, try mount. backupserver-sd: acquire.c:424 Do mount_next_write_vol backupserver-sd: mount.c:74 Enter mount_next_volume(release=0) dev="DLT-V4" (/dev/nst0) backupserver-sd: mount.c:84 mount_next_vol retry=0 backupserver-sd: mount.c:110 Before dir_find_next_appendable_volume. backupserver-sd: askdir.c:256 dir_find_next_appendable_volume backupserver-sd: askdir.c:271 >dird: CatReq Job=fileserver-backup.2007-10-22_18.54.33 FindMedia=1 pool_name=Daily media_type=DLT-V4 backupserver-sd: askdir.c:182 <dird 1000 OK VolName=CNI906 VolJobs=15 VolFiles=1 VolBlocks=6684 VolBytes=431262720 VolMounts=6 VolErrors=0 VolWrites=6686 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Append Slot=3 MaxVolJobs=0 MaxVolFiles=0 InChanger=1 VolReadTime=0 VolWriteTime=4716850 EndFile=0 EndBlock=6684 VolParts=0 LabelType=0 MediaId=11 backupserver-sd: askdir.c:204 do_reqest_vol_info return true slot=3 Volume=CNI906 backupserver-sd: reserve.c:406 jid=472 find_vol=CNI906 found=1 backupserver-sd: reserve.c:181 jid=472 List from find_volume: CNI906 at 80c9f98 on device "DLT-V4" (/dev/nst0) backupserver-sd: reserve.c:538 jid=472 Vol=CNI906 on same dev. backupserver-sd: reserve.c:313 jid=472 reserve_volume CNI906 backupserver-sd: reserve.c:181 jid=472 List from begin reserve_volume: CNI906 at 80c9f98 on device "DLT-V4" (/dev/nst0) backupserver-sd: reserve.c:181 jid=472 List from end new volume: CNI906 at 80c9f98 on device "DLT-V4" (/dev/nst0) backupserver-sd: mount.c:122 After find_next_append. Vol=CNI906 Slot=3 Parts=0 backupserver-sd: autochanger.c:217 After changer, status=0 backupserver-sd: mount.c:142 autoload_dev returns 1 backupserver-sd: mount.c:150 (1)Ask=0 backupserver-sd: mount.c:158 Ask=0 autochanger=1 backupserver-sd: mount.c:168 want vol=CNI906 backupserver-sd: dev.c:283 Close fd for mode change. backupserver-sd: dev.c:292 open dev: type=2 dev_name="DLT-V4" (/dev/nst0) vol=CNI906 mode=OPEN_READ_WRITE backupserver-sd: dev.c:342 Open dev: device is tape backupserver-sd: autochanger.c:299 Locking changer Autochanger backupserver-sd: autochanger.c:269 Run program=/usr/libexec/bacula/mtx-changer /dev/sg1 loaded 3 /dev/nst0 0 backupserver-sd: autochanger.c:271 run_prog: /usr/libexec/bacula/mtx-changer /dev/sg1 loaded 3 /dev/nst0 0 stat=0 result=3 backupserver-sd: autochanger.c:308 Unlocking changer Autochanger backupserver-sd: dev.c:357 Try open "DLT-V4" (/dev/nst0) mode=OPEN_READ_WRITE backupserver-sd: dev.c:379 Rewind after open backupserver-sd: dev.c:2360 In set_os_device_parameters backupserver-sd: dev.c:2366 Set block size to zero backupserver-sd: dev.c:431 open dev: tape 4 opened backupserver-sd: dev.c:306 preserve=0x40 fd=4 backupserver-sd: label.c:81 Enter read_volume_label res=1 device="DLT-V4" (/dev/nst0) vol=CNI906 dev_Vol=CNI906 backupserver-sd: label.c:104 Leave read_volume_label() VOL_OK backupserver-sd: mount.c:237 Want dirVol=CNI906 dirStat=Append backupserver-sd: mount.c:244 Vol OK name=CNI906 backupserver-sd: mount.c:376 Device previously written, moving to end of data backupserver-sd: dev.c:827 eod backupserver-sd: dev.c:874 Using EOM for EOM backupserver-sd: dev.c:897 EOD file=1 backupserver-sd: dev.c:954 EOD dev->file=1 backupserver-sd: mount.c:434 update volinfo mounts=7 backupserver-sd: askdir.c:339 Update cat VolFiles=1 backupserver-sd: askdir.c:358 >dird: CatReq Job=fileserver-backup.2007-10-22_18.54.33 UpdateMedia VolName=CNI906 VolJobs=15 VolFiles=1 VolBlocks=6684 VolBytes=431262720 VolMounts=7 VolErrors=0 VolWrites=6686 MaxVolBytes=0 EndTime=1193079396 VolStatus=Append Slot=3 relabel=0 InChanger=1 VolReadTime=0 VolWriteTime=4716850 VolFirstWritten=0 VolParts=0 backupserver-sd: askdir.c:182 <dird 1000 OK VolName=CNI906 VolJobs=15 VolFiles=1 VolBlocks=6684 VolBytes=431262720 VolMounts=7 VolErrors=0 VolWrites=6686 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Append Slot=3 MaxVolJobs=0 MaxVolFiles=0 InChanger=1 VolReadTime=0 VolWriteTime=4716850 EndFile=0 EndBlock=6684 VolParts=0 LabelType=0 MediaId=11 backupserver-sd: askdir.c:204 do_reqest_vol_info return true slot=3 Volume=CNI906 backupserver-sd: mount.c:473 set APPEND, normal return from mount_next_write_volume. dev="DLT-V4" (/dev/nst0) backupserver-sd: acquire.c:436 Output pos=1:0 backupserver-sd: askdir.c:339 Update cat VolFiles=1 backupserver-sd: askdir.c:358 >dird: CatReq Job=fileserver-backup.2007-10-22_18.54.33 UpdateMedia VolName=CNI906 VolJobs=16 VolFiles=1 VolBlocks=6684 VolBytes=431262720 VolMounts=7 VolErrors=0 VolWrites=6686 MaxVolBytes=0 EndTime=1193079396 VolStatus=Append Slot=3 relabel=0 InChanger=1 VolReadTime=0 VolWriteTime=4716850 VolFirstWritten=0 VolParts=0 backupserver-sd: askdir.c:182 <dird 1000 OK VolName=CNI906 VolJobs=16 VolFiles=1 VolBlocks=6684 VolBytes=431262720 VolMounts=7 VolErrors=0 VolWrites=6686 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Append Slot=3 MaxVolJobs=0 MaxVolFiles=0 InChanger=1 VolReadTime=0 VolWriteTime=4716850 EndFile=0 EndBlock=6684 VolParts=0 LabelType=0 MediaId=11 backupserver-sd: askdir.c:204 do_reqest_vol_info return true slot=3 Volume=CNI906 backupserver-sd: acquire.c:448 Dec reserve=0 dev="DLT-V4" (/dev/nst0) backupserver-sd: jcr.c:602 OnEntry JobStatus=R set=R backupserver-sd: jcr.c:622 OnExit JobStatus=R set=R backupserver-sd: append.c:96 Begin append device="DLT-V4" (/dev/nst0) backupserver-sd: spool.c:110 Turning on data spooling backupserver-sd: spool.c:179 Created spool file: /var/data/amanda/bacula/spool/backupserver-sd.data.472.fileserver-backup.2007-10-22_18.54.33.DLT-V4.spool backupserver-sd: append.c:101 Just after acquire_device_for_append backupserver-sd: label.c:698 session_label record=80cc220 backupserver-sd: label.c:754 Write sesson_label record JobId=472 FI=SOS_LABEL SessId=1 Strm=472 len=179 remainder=0 backupserver-sd: label.c:758 Leave write_session_label Block=0d File=1d backupserver-sd: bnet.c:666 who=client host=192.168.1.30 port=36643 backupserver-sd: dircmd.c:171 Conn: Hello Director fileserver-dir calling backupserver-sd: dircmd.c:181 Got a DIR connection backupserver-sd: jcr.c:602 OnEntry JobStatus=backupserver-sd: jcr.c:622 OnExit JobStatus=C set=C backupserver-sd: cram-md5.c:71 send: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 backupserver-sd: cram-md5.c:131 cram-get: auth cram-md5 <[EMAIL PROTECTED]> ssl=0 backupserver-sd: cram-md5.c:150 sending resp to challenge: p8/qo/5jVDAXL//8+/4bbD backupserver-sd: dircmd.c:202 Message channel init completed. backupserver-sd: dircmd.c:209 <dird: status backupserver-sd: dircmd.c:223 Do command: status backupserver-sd: pythonlib.c:237 No startup module. backupserver-sd: mem_pool.c:377 garbage collect memory pool backupserver ~ # Many thanks Spencer ------------------------------------------------------------------------- This SF.net email is sponsored by: Splunk Inc. Still grepping through log files to find problems? Stop. Now Search log events and configuration files using AJAX and a browser. Download your FREE copy of Splunk now >> http://get.splunk.com/ _______________________________________________ Bacula-users mailing list Bacula-users@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/bacula-users
