06-Aug-2014 16:47:04 backup.test-sd: mac.c:435-0 Start read data.
06-Aug-2014 16:47:04 backup.test-sd: mac.c:572-0 read_dcr=7f82ec016028 write_dcr=7f82ec026738
06-Aug-2014 16:47:04 backup.test-sd: mac.c:574-0 Found 3 volumes names for Virtual Backup. First=library.test.0895
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:63-0 dcr=7f82ec016028 dev=7f82ec0016d8
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:64-0 MediaType dcr=backup.test.filestorage dev=backup.test.filestorage
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:92-0 Want Vol=library.test.0895 Slot=0
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:106-0 MediaType dcr=library.test.filestorage dev=backup.test.filestorage
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:117-0 Changing read device. Want Media Type="library.test.filestorage" have="backup.test.filestorage"
  device="backup.test.filestorage-1" (/var/backup/backup.test.filestorage/)
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:473-0 release_device device "backup.test.filestorage-1" (/var/backup/backup.test.filestorage/) is disk
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:134-0 Dec reserve=0 dev="backup.test.filestorage-1" (/var/backup/backup.test.filestorage/)
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:484-0 dir_update_vol_info. label=0 Vol=
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:537-0 0 writers, 0 reserve, dev="backup.test.filestorage-1" (/var/backup/backup.test.filestorage/)
06-Aug-2014 16:47:04 backup.test-sd: dev.c:883-0 close_dev "backup.test.filestorage-1" (/var/backup/backup.test.filestorage/)
06-Aug-2014 16:47:04 backup.test-sd: dev.c:890-0 device "backup.test.filestorage-1" (/var/backup/backup.test.filestorage/) already closed vol=
06-Aug-2014 16:47:04 backup.test-sd: vol_mgr.c:732-0 No vol on dev "backup.test.filestorage-1" (/var/backup/backup.test.filestorage/)
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:595-0 JobId=4123 broadcast wait_device_release at 06-Aug-2014 16:47:04
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:781-0 Detach Jid=4123 dcr=7f82ec016028 size=1 to dev="backup.test.filestorage-1" (/var/backup/backup.test.filestorage/)
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:617-0 Device "backup.test.filestorage-1" (/var/backup/backup.test.filestorage/) released by JobId=4123
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:557-0 search res for library.test.filestorage-1
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-1
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-2
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-3
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-4
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-5
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-6
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-7
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-8
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-9
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-10
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.copy.filestorage-1
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=backup.test.copy.filestorage-2
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=library.test.filestorage-1
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:628-0 chk MediaType device=library.test.filestorage request=library.test.filestorage
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:651-0 try reserve library.test.filestorage-1
06-Aug-2014 16:47:04 backup.test-sd: block.c:127-0 created new block of blocksize 64512 (dev->device->label_block_size) as dev->max_block_size is zero
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:760-0 Attach Jid=4123 dcr=7f82ec016028 size=1 dev="library.test.filestorage-1" (/var/backup/library.test.filestorage/)
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:804-0 Device "library.test.filestorage-1" (/var/backup/library.test.filestorage/) is busy ST_READREADY=0 num_writers=0 reserved=1.
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:770-0 Not OK.
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=library.test.filestorage-2
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=library.test.filestorage.alternative-1
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=library.test.filestorage.alternative-2
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=archive.test.filestorage-1
06-Aug-2014 16:47:04 backup.test-sd: reserve.c:591-0 Try match res=archive.test.filestorage-2
06-Aug-2014 16:47:04 backup.test-sd: acquire.c:170-0 No suitable device found to read Volume "library.test.0895"