06-Aug-2014 16:53:13 backup.test-sd: mac.c:435-0 Start read data.
06-Aug-2014 16:53:13 backup.test-sd: mac.c:572-0 read_dcr=7f5de4004a08 write_dcr=7f5de4015118
06-Aug-2014 16:53:13 backup.test-sd: mac.c:574-0 Found 3 volumes names for Virtual Backup. First=library.test.0895
06-Aug-2014 16:53:13 backup.test-sd: acquire.c:63-0 dcr=7f5de4004a08 dev=7f5de80016c8
06-Aug-2014 16:53:13 backup.test-sd: acquire.c:64-0 MediaType dcr=backup.test.filestorage dev=backup.test.filestorage
06-Aug-2014 16:53:13 backup.test-sd: acquire.c:92-0 Want Vol=library.test.0895 Slot=0
06-Aug-2014 16:53:13 backup.test-sd: acquire.c:106-0 MediaType dcr=library.test.filestorage dev=backup.test.filestorage
06-Aug-2014 16:53:13 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:53:13 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:53:13 backup.test-sd: reserve.c:134-0 Dec reserve=0 dev="backup.test.filestorage-1" (/var/backup/backup.test.filestorage/)
06-Aug-2014 16:53:13 backup.test-sd: acquire.c:484-0 dir_update_vol_info. label=0 Vol=
06-Aug-2014 16:53:13 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:53:13 backup.test-sd: dev.c:883-0 close_dev "backup.test.filestorage-1" (/var/backup/backup.test.filestorage/)
06-Aug-2014 16:53:13 backup.test-sd: dev.c:890-0 device "backup.test.filestorage-1" (/var/backup/backup.test.filestorage/) already closed vol=
06-Aug-2014 16:53:13 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:53:13 backup.test-sd: acquire.c:595-0 JobId=4124 broadcast wait_device_release at 06-Aug-2014 16:53:13
06-Aug-2014 16:53:13 backup.test-sd: acquire.c:781-0 Detach Jid=4124 dcr=7f5de4004a08 size=1 to dev="backup.test.filestorage-1" (/var/backup/backup.test.filestorage/)
06-Aug-2014 16:53:13 backup.test-sd: acquire.c:617-0 Device "backup.test.filestorage-1" (/var/backup/backup.test.filestorage/) released by JobId=4124
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:557-0 search res for library.test.filestorage-1
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-1
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-2
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-3
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-4
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-5
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-6
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-7
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-8
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-9
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.filestorage-10
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.copy.filestorage-1
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=backup.test.copy.filestorage-2
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=library.test.filestorage-1
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:643-0 chk MediaType device=library.test.filestorage request=library.test.filestorage
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:666-0 try reserve library.test.filestorage-1
06-Aug-2014 16:53:13 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:53:13 backup.test-sd: acquire.c:760-0 Attach Jid=4124 dcr=7f5de4004a08 size=1 dev="library.test.filestorage-1" (/var/backup/library.test.filestorage/)
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:819-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:53:13 backup.test-sd: reserve.c:785-0 Not OK.
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:591-0 Try match res=library.test.filestorage-2
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:643-0 chk MediaType device=library.test.filestorage request=library.test.filestorage
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:666-0 try reserve library.test.filestorage-2
06-Aug-2014 16:53:13 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:53:13 backup.test-sd: acquire.c:781-0 Detach Jid=4124 dcr=7f5de4004a08 size=2 to dev="library.test.filestorage-1" (/var/backup/library.test.filestorage/)
06-Aug-2014 16:53:13 backup.test-sd: acquire.c:760-0 Attach Jid=4124 dcr=7f5de4004a08 size=0 dev="library.test.filestorage-2" (/var/backup/library.test.filestorage/)
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:125-0 Inc reserve=0 dev="library.test.filestorage-2" (/var/backup/library.test.filestorage/)
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:763-0 Read reserved=1 dev_name=library.test.filestorage-1 mediatype=library.test.filestorage pool=db2.dev.riege.de ok=1
06-Aug-2014 16:53:13 backup.test-sd: reserve.c:619-0 Device library.test.filestorage-2 reserved=1 for read.