Hello, This looks like bug #1227, but since I no longer have much time for support, I didn't look carefully at your output.
This problem is fixed in the current beta release, and I'll probably release a patch for the 2.4 Branch a bit later today. Regards, Kern On Wednesday 18 March 2009 11:44:47 Stephen Maher wrote: > Hi Kern.. > > You have helped me in the past via email.. and hoping to chance my arm > again :-) > > I think I might have found a potential bug with bacula.. but would > need some help in diagnosing. > Basically its asking for tape 38 yet tape 38 is there and in the > drive. The director keeps stating to insert the tape.. > > Could you please have a look down below and see if its a potential bug? > > If you need any more info please let me know > > Many thanks > Stephen > > > 17-Mar 04:45 mop-sd JobId 49: Please mount Volume "000038" or label a > new one for: > Job: bushmills.2009-03-13_20.30.16 > Storage: "124t-1" (/dev/nst0) > Pool: Default > Media type: LTO-1 > *list media > Pool: Default > +---------+------------+-----------+---------+-----------------+----------+ >--------------+---------+------+-----------+-----------+-------------------- >-+ > > | MediaId | VolumeName | VolStatus | Enabled | VolBytes | > > VolFiles | VolRetention | Recycle | Slot | InChanger | MediaType | > LastWritten | > +---------+------------+-----------+---------+-----------------+----------+ >--------------+---------+------+-----------+-----------+-------------------- >-+ > > | 1 | 000025 | Full | 1 | 128,079,866,880 | > > 129 | 2,592,000 | 1 | 1 | 0 | LTO-1 | > 2009-02-27 23:08:07 | > > | 2 | 000026 | Full | 1 | 107,123,143,680 | > > 108 | 2,592,000 | 1 | 2 | 0 | LTO-1 | > 2009-02-28 01:25:47 | > > | 3 | 000027 | Full | 1 | 106,705,299,456 | > > 107 | 2,592,000 | 1 | 3 | 0 | LTO-1 | > 2009-02-28 03:21:19 | > > | 4 | 000028 | Full | 1 | 122,908,520,448 | > > 123 | 2,592,000 | 1 | 4 | 0 | LTO-1 | > 2009-02-28 05:38:32 | > > | 5 | 000029 | Full | 1 | 131,950,715,904 | > > 132 | 2,592,000 | 1 | 5 | 0 | LTO-1 | > 2009-02-28 08:00:14 | > > | 6 | 000030 | Full | 1 | 110,486,799,360 | > > 111 | 2,592,000 | 1 | 6 | 0 | LTO-1 | > 2009-02-28 10:05:31 | > > | 7 | 000031 | Full | 1 | 115,940,837,376 | > > 116 | 2,592,000 | 1 | 7 | 0 | LTO-1 | > 2009-02-28 12:10:56 | > > | 8 | 000032 | Full | 1 | 161,427,474,432 | > > 162 | 2,592,000 | 1 | 8 | 0 | LTO-1 | > 2009-02-28 14:57:15 | > > | 9 | 000033 | Full | 1 | 146,252,058,624 | > > 147 | 2,592,000 | 1 | 9 | 0 | LTO-1 | > 2009-02-28 17:05:58 | > > | 10 | 000034 | Full | 1 | 169,546,567,680 | > > 173 | 2,592,000 | 1 | 10 | 0 | LTO-1 | > 2009-03-05 00:15:14 | > > | 11 | 000035 | Full | 1 | 133,992,133,632 | > > 138 | 2,592,000 | 1 | 1 | 1 | LTO-1 | > 2009-03-07 00:29:44 | > > | 26 | 000037 | Full | 1 | 129,891,492,864 | > > 131 | 2,592,000 | 1 | 3 | 1 | LTO-1 | > 2009-03-06 23:02:47 | > > | 27 | 000036 | Full | 1 | 162,726,617,088 | > > 169 | 2,592,000 | 1 | 2 | 1 | LTO-1 | > 2009-03-13 21:42:32 | > > | 28 | 000038 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 4 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 29 | 000039 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 5 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 30 | 000040 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 6 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 31 | 000041 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 7 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 32 | 000042 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 8 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 33 | 000043 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 9 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 34 | 000044 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 10 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 35 | 000045 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 11 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 36 | 000046 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 12 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 37 | 000047 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 13 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 38 | 000048 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 14 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 39 | 000049 | Append | 1 | 64,512 | > > 0 | 2,592,000 | 1 | 15 | 1 | LTO-1 | > 0000-00-00 00:00:00 | > > | 40 | 000050 | Full | 1 | 174,568,439,808 | > > 181 | 2,592,000 | 1 | 16 | 1 | LTO-1 | > 2009-03-13 00:03:37 | > +---------+------------+-----------+---------+-----------------+----------+ >--------------+---------+------+-----------+-----------+-------------------- >-+ r...@mop:~# bacula-sd -c /etc/bacula/bacula-sd.conf -f -d99 -v > bacula-sd: stored_conf.c:672-0 Inserting director res: mop-mon > bacula-sd: stored_conf.c:672-0 Inserting device res: 124t-1 > mop-sd: stored.c:480-0 calling init_dev /tmp/ > mop-sd: stored.c:482-0 SD init done /tmp/ > mop-sd: stored.c:480-0 calling init_dev /dev/nst0 > mop-sd: stored.c:482-0 SD init done /dev/nst0 > mop-sd: cram-md5.c:73-0 send: auth cram-md5 <200307337.1237372...@mop-sd> > ssl=0 mop-sd: cram-md5.c:152-0 sending resp to challenge: > mop-sd: dircmd.c:210-0 Message channel init completed. > mop-sd: reserve.c:695-0 jid=60 <dird: use storage=PowerVault > media_type=LTO-1 pool_name=Default pool_type=Backup append=1 copy=0 > stripe=0 > mop-sd: reserve.c:724-0 jid=60 <dird device: use device=PowerVault > mop-sd: reserve.c:741-0 jid=60 Storage=PowerVault media_type=LTO-1 > pool=Default pool_type=Backup append=1 > mop-sd: reserve.c:743-0 jid=60 Device=PowerVault > mop-sd: reserve.c:919-0 jid=60 PrefMnt=1 exact=1 suitable=0 chgronly=0 > mop-sd: reserve.c:1084-0 jid=60 search res for PowerVault > mop-sd: reserve.c:1087-0 jid=60 Try match changer res=PowerVault > mop-sd: reserve.c:1098-0 jid=60 Try changer device 124t-1 > mop-sd: reserve.c:1161-0 jid=60 chk MediaType device=LTO-1 request=LTO-1 > mop-sd: reserve.c:1184-0 try reserve 124t-1 > mop-sd: reserve.c:1198-0 jid=60 have_vol=0 vol= > mop-sd: reserve.c:1381-0 jid=60 reserve_append device is "124t-1" > (/dev/nst0) mop-sd: reserve.c:1459-0 jid=60 PrefMnt=1 exact=1 suitable=1 > chgronly=0 any=0 mop-sd: reserve.c:1430-0 MaxJobs=0 Jobs=0 reserves=0 > Status= Vol= > mop-sd: reserve.c:1503-0 jid=60 failed: want mounted -- no vol > mop-sd: reserve.c:1385-0 jid=60 can_reserve_drive!=1 > mop-sd: reserve.c:1287-0 jid=60 Not OK. > mop-sd: reserve.c:1121-0 jid=60 Try match res=FileStorage > mop-sd: reserve.c:1121-0 jid=60 Try match res=124t-1 > mop-sd: reserve.c:1060-0 jid=60 No usable device found. > mop-sd: reserve.c:1070-0 Leave find_suit_dev: no dev found. > mop-sd: reserve.c:919-0 jid=60 PrefMnt=1 exact=0 suitable=1 chgronly=0 > mop-sd: reserve.c:1084-0 jid=60 search res for PowerVault > mop-sd: reserve.c:1087-0 jid=60 Try match changer res=PowerVault > mop-sd: reserve.c:1098-0 jid=60 Try changer device 124t-1 > mop-sd: reserve.c:1161-0 jid=60 chk MediaType device=LTO-1 request=LTO-1 > mop-sd: reserve.c:1184-0 try reserve 124t-1 > mop-sd: reserve.c:1198-0 jid=60 have_vol=0 vol= > mop-sd: reserve.c:1381-0 jid=60 reserve_append device is "124t-1" > (/dev/nst0) mop-sd: reserve.c:1459-0 jid=60 PrefMnt=1 exact=0 suitable=1 > chgronly=0 any=0 mop-sd: reserve.c:1430-0 MaxJobs=0 Jobs=0 reserves=0 > Status= Vol= > mop-sd: reserve.c:1503-0 jid=60 failed: want mounted -- no vol > mop-sd: reserve.c:1385-0 jid=60 can_reserve_drive!=1 > mop-sd: reserve.c:1287-0 jid=60 Not OK. > mop-sd: reserve.c:1121-0 jid=60 Try match res=FileStorage > mop-sd: reserve.c:1121-0 jid=60 Try match res=124t-1 > mop-sd: reserve.c:1060-0 jid=60 No usable device found. > mop-sd: reserve.c:1070-0 Leave find_suit_dev: no dev found. > mop-sd: reserve.c:919-0 jid=60 PrefMnt=1 exact=0 suitable=1 chgronly=0 > mop-sd: reserve.c:1084-0 jid=60 search res for PowerVault > mop-sd: reserve.c:1087-0 jid=60 Try match changer res=PowerVault > mop-sd: reserve.c:1098-0 jid=60 Try changer device 124t-1 > mop-sd: reserve.c:1161-0 jid=60 chk MediaType device=LTO-1 request=LTO-1 > mop-sd: reserve.c:1184-0 try reserve 124t-1 > mop-sd: reserve.c:1198-0 jid=60 have_vol=0 vol= > mop-sd: reserve.c:1381-0 jid=60 reserve_append device is "124t-1" > (/dev/nst0) mop-sd: reserve.c:1459-0 jid=60 PrefMnt=1 exact=0 suitable=1 > chgronly=0 any=1 mop-sd: reserve.c:1430-0 MaxJobs=0 Jobs=0 reserves=0 > Status= Vol= > mop-sd: reserve.c:1566-0 jid=60 OK Dev avail reserved "124t-1" (/dev/nst0) > mop-sd: reserve.c:481-0 Inc reserve=0 dev="124t-1" (/dev/nst0) > mop-sd: reserve.c:1208-0 jid=60 Reserved=1 dev_name=PowerVault > mediatype=LTO-1 pool=Default ok=1 > mop-sd: reserve.c:472-0 jid=60 find_vol=000038 found=0 > mop-sd: reserve.c:635-0 jid=60 Vol=000038 not in use. > mop-sd: reserve.c:316-0 jid=60 enter reserve_volume=000038 > drive="124t-1" (/dev/nst0) > mop-sd: reserve.c:236-0 jid=60 new Vol=000038 at 9585708 dev="124t-1" > (/dev/nst0) > mop-sd: reserve.c:418-0 jid=60 === set in_use. vol=000038 dev="124t-1" > (/dev/nst0) > mop-sd: reserve.c:189-0 jid=60 List end new volume: 000038 in_use=1 on > device "124t-1" (/dev/nst0) > mop-sd: reserve.c:1220-0 jid=60 looking for Volume=000038 > mop-sd: reserve.c:1279-0 jid=60 >dird changer: 3000 OK use device > device=124t-1 mop-sd: reserve.c:1107-0 jid=60 Device 124t-1 reserved=1 for > append. mop-sd: reserve.c:1053-0 jid=60 available device found=PowerVault > mop-sd: reserve.c:1068-0 OK dev found. Vol=000038 > mop-sd: job.c:190-0 firewater.2009-03-18_03.40.04 waiting 1800 sec for > FD to contact SD key= > mop-sd: cram-md5.c:73-0 send: auth cram-md5 <1116341978.1237372...@mop-sd> > ssl=0 mop-sd: cram-md5.c:152-0 sending resp to challenge: > mop-sd: mount.c:230-0 set_unload > mop-sd: mount.c:230-0 set_unload > mop-sd: mount.c:230-0 set_unload > mop-sd: mount.c:230-0 set_unload > mop-sd: mount.c:230-0 set_unload ------------------------------------------------------------------------------ _______________________________________________ Bacula-users mailing list Bacula-users@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/bacula-users
