muc1pro-backup-1-dir (100): backup.c:359-8021 JobId=8021 JobLevel=I
muc1pro-backup-1-dir (100): sd_cmds.c:110-8021 bnet_connect to Storage daemon muc1pro-backup-1.adm.financial.com:9103
muc1pro-backup-1-dir (100): bsock.c:70-8021 All source addresses 
muc1pro-backup-1-dir (100): bsock_tcp.c:230-8021 Current host[ipv4;10.0.1.233;9103]  All host[ipv4;10.0.1.233;9103] 
muc1pro-backup-1-dir (100): bsock_tcp.c:153-8021 who=Storage daemon host=muc1pro-backup-1.adm.financial.com port=9103
muc1pro-backup-1-dir (100): cram-md5.c:123-8021 cram-get received: auth cram-md5 <1048758182.1481808200@muc1pro-backup-1-sd> ssl=0
muc1pro-backup-1-dir (99): cram-md5.c:143-8021 sending resp to challenge: ++BR4T4Bk5/Ru0+qS8FKdA
muc1pro-backup-1-dir (50): cram-md5.c:75-8021 send: auth cram-md5 <2130647233.1481808200@muc1pro-backup-1-dir> ssl=0
muc1pro-backup-1-dir (50): cram-md5.c:94-8021 Authenticate OK 6U8067MuCR+BygxrPjFwpB
muc1pro-backup-1-dir (50): msgchan.c:212-8021 Remainingquota: 0
muc1pro-backup-1-dir (100): msgchan.c:223-8021 >stored: JobId=8021 job=bg1nc0502.2016-12-15_14.23.18_33 job_name=bg1nc0502 client_name=bg1nc0502 type=66 level=73 FileSet=bg1nc0502 NoAttr=0 SpoolAttr=0 FileSetMD5=Ik+RFE/EoiYTs78LbB+MCA SpoolData=0 PreferMountedVols=1 SpoolSize=0 rerunning=0 VolSessionId=0 VolSessionTime=0 Quota=0 Protocol=0 BackupFormat=Native
muc1pro-backup-1-dir (100): msgchan.c:225-8021 <stored: 3000 OK Job SDid=77 SDtime=1481703237 Authorization=KGGO-GGFH-GLLB-DEDM-PHCP-OCBA-BJBJ-GKFI
muc1pro-backup-1-dir (100): msgchan.c:315-8021 wstore >stored: use storage=DiskStorageIncr media_type=FileIncr pool_name=DiskDaily pool_type=Backup append=1 copy=0 stripe=0
muc1pro-backup-1-dir (100): msgchan.c:322-8021 >stored: use device=DiskDeviceIncr1
muc1pro-backup-1-dir (100): msgchan.c:322-8021 >stored: use device=DiskDeviceIncr2
muc1pro-backup-1-dir (100): msgchan.c:322-8021 >stored: use device=DiskDeviceIncr3
muc1pro-backup-1-dir (100): msgchan.c:322-8021 >stored: use device=DiskDeviceIncr4
muc1pro-backup-1-dir (100): msgchan.c:322-8021 >stored: use device=DiskDeviceIncr5
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 GetVolInfo VolName=Incr-3669 write=1
muc1pro-backup-1-dir (100): catreq.c:165-8021 CatReq GetVolInfo Vol=Incr-3669
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName=Incr-3669 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=1 VolErrors=0 VolWrites=166441 MaxVolBytes=10737418240 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=22855150 EndFile=2 EndBlock=2147442907 LabelType=0 MediaId=3669 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=1 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=1 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=1 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=3669
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=1 vol=Incr-3669
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName=Incr-3669 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=1 VolErrors=0 VolWrites=166441 MaxVolBytes=10737418240 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=22855150 EndFile=2 EndBlock=2147442907 LabelType=0 MediaId=3669 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=2 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=Incr-3669
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 2
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=2 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=2 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=0
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=2 vol=
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName= VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=0 VolMounts=0 VolErrors=0 VolWrites=0 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=0 EndFile=0 EndBlock=0 LabelType=0 MediaId=0 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): msgchan.c:328-8021 <stored: 3000 OK use device device=DiskDeviceIncr2
muc1pro-backup-1-dir (100): bsock.c:70-8021 All source addresses 
muc1pro-backup-1-dir (100): bsock_tcp.c:230-8021 Current host[ipv4;192.168.210.31;9102]  All host[ipv4;192.168.210.31;9102] 
muc1pro-backup-1-dir (100): bsock_tcp.c:153-8021 who=Client: bg1nc0502 host=bg1nc0502.lan.muc.financial.com port=9102
muc1pro-backup-1-dir (10): fd_cmds.c:126-8021 Opened connection with File daemon
muc1pro-backup-1-dir (50): authenticate.c:288-8021 Sent: Hello Director muc1pro-backup-1-dir calling
muc1pro-backup-1-dir (100): cram-md5.c:123-8021 cram-get received: auth cram-md5 <1661742110.1481808200@bg1nc0502> ssl=0
muc1pro-backup-1-dir (99): cram-md5.c:143-8021 sending resp to challenge: mwIl3/B3a880GH/Fh6ZZVC
muc1pro-backup-1-dir (50): cram-md5.c:75-8021 send: auth cram-md5 <1846417091.1481808200@muc1pro-backup-1-dir> ssl=0
muc1pro-backup-1-dir (50): cram-md5.c:94-8021 Authenticate OK VAkyA4I+wT/aE9g7Yl/yjB
muc1pro-backup-1-dir (100): fd_cmds.c:151-8021 >filed: JobId=8021 Job=bg1nc0502.2016-12-15_14.23.18_33 SDid=77 SDtime=1481703237 Authorization=KGGO-GGFH-GLLB-DEDM-PHCP-OCBA-BJBJ-GKFI
muc1pro-backup-1-dir (100): msgchan.c:366-8021 Start SD msg_thread.
muc1pro-backup-1-dir (100): msgchan.c:424-8021 Start msg_thread loop
muc1pro-backup-1-dir (100): msgchan.c:378-8021 SD msg_thread started. use=2
muc1pro-backup-1-dir (1): sql_get.c:1286-8021 db_accurate_get_jobids=7405,7660,7813
muc1pro-backup-1-dir (50): backup.c:246-8021 Checksum will be sent to FD
muc1pro-backup-1-dir (100): sql_pooling.c:60-8021 db_sql_get_non_pooled_connection allocating 1 new non pooled database connection to database bareos, backend type MySQL
muc1pro-backup-1-dir (100): cats_backends.c:63-8021 db_init_database: Trying to find mapping of given interfacename MySQL to mapping interfacename dbi, partly_compare = true
muc1pro-backup-1-dir (100): cats_backends.c:63-8021 db_init_database: Trying to find mapping of given interfacename MySQL to mapping interfacename mysql, partly_compare = false
muc1pro-backup-1-dir (100): mysql.c:851-8021 db_init_database first time
muc1pro-backup-1-dir (50): mysql.c:172-8021 mysql_init done
muc1pro-backup-1-dir (50): mysql.c:196-8021 mysql_real_connect done
muc1pro-backup-1-dir (50): mysql.c:198-8021 db_user=bareos db_name=bareos db_password=bareos
muc1pro-backup-1-dir (100): mysql.c:221-8021 opendb ref=1 connected=1 db=7f881c0240b0
muc1pro-backup-1-dir (100): sql_get.c:1183-8021 q=SELECT Path.Path, Filename.Name, T1.FileIndex, T1.JobId, LStat, DeltaSeq      FROM ( SELECT FileId, Job.JobId AS JobId, FileIndex, File.PathId AS PathId, File.FilenameId AS FilenameId, LStat     , DeltaSeq, Job.JobTDate AS JobTDate FROM Job, File, ( SELECT MAX(JobTDate) AS JobTDate, PathId, FilenameId FROM ( SELECT JobTDate, PathId, FilenameId FROM File JOIN Job USING (JobId) WHERE File.JobId IN (7405,7660,7813) UNION ALL SELECT JobTDate, PathId, FilenameId FROM BaseFiles JOIN File USING (FileId) JOIN Job  ON    (BaseJobId = Job.JobId) WHERE BaseFiles.JobId IN (7405,7660,7813) ) AS tmp GROUP BY PathId, FilenameId ) AS T1 WHERE (Job.JobId IN ( SELECT DISTINCT BaseJobId FROM BaseFiles WHERE JobId IN (7405,7660,7813)) OR Job.JobId IN (7405,7660,7813)) AND T1.JobTDate = Job.JobTDate AND Job.JobId = File.JobId AND T1.PathId = File.PathId AND T1.FilenameId = File.FilenameId ) AS T1 JOIN Filename ON (Filename.FilenameId = T1.FilenameId) JOIN Path ON (Path.PathId = T1.PathId) WHERE FileIndex > 0 ORDER BY T1.JobTDate, FileIndex ASC
muc1pro-backup-1-dir (100): backup.c:583-8021 >filed: backup FileIndex=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 GetVolInfo VolName= write=1
muc1pro-backup-1-dir (100): catreq.c:165-8021 CatReq GetVolInfo Vol=
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=1 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=1 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=1 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=3669
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=1 vol=Incr-3669
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName=Incr-3669 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=1 VolErrors=0 VolWrites=166441 MaxVolBytes=10737418240 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=22855150 EndFile=2 EndBlock=2147442907 LabelType=0 MediaId=3669 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=2 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=Incr-3669
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 2
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=2 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=2 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=0
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=2 vol=
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName= VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=0 VolMounts=0 VolErrors=0 VolWrites=0 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=0 EndFile=0 EndBlock=0 LabelType=0 MediaId=0 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=1 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=1 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=1 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=3669
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=1 vol=Incr-3669
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName=Incr-3669 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=1 VolErrors=0 VolWrites=166441 MaxVolBytes=10737418240 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=22855150 EndFile=2 EndBlock=2147442907 LabelType=0 MediaId=3669 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=2 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=Incr-3669
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 2
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=2 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=2 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=0
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=2 vol=
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName= VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=0 VolMounts=0 VolErrors=0 VolWrites=0 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=0 EndFile=0 EndBlock=0 LabelType=0 MediaId=0 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=1 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=1 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=1 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=3669
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=1 vol=Incr-3669
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName=Incr-3669 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=1 VolErrors=0 VolWrites=166441 MaxVolBytes=10737418240 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=22855150 EndFile=2 EndBlock=2147442907 LabelType=0 MediaId=3669 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=2 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=Incr-3669
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 2
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=2 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=2 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=0
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=2 vol=
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName= VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=0 VolMounts=0 VolErrors=0 VolWrites=0 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=0 EndFile=0 EndBlock=0 LabelType=0 MediaId=0 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=1 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=1 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=1 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=3669
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=1 vol=Incr-3669
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName=Incr-3669 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=1 VolErrors=0 VolWrites=166441 MaxVolBytes=10737418240 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=22855150 EndFile=2 EndBlock=2147442907 LabelType=0 MediaId=3669 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=2 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=Incr-3669
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 2
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=2 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=2 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=0
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=2 vol=
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName= VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=0 VolMounts=0 VolErrors=0 VolWrites=0 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=0 EndFile=0 EndBlock=0 LabelType=0 MediaId=0 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=1 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=1 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=1 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=3669
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=1 vol=Incr-3669
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName=Incr-3669 VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=1 VolMounts=1 VolErrors=0 VolWrites=166441 MaxVolBytes=10737418240 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=1 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=22855150 EndFile=2 EndBlock=2147442907 LabelType=0 MediaId=3669 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): catreq.c:124-8021 catreq CatReq Job=bg1nc0502.2016-12-15_14.23.18_33 FindMedia=2 pool_name=DiskDaily media_type=FileIncr unwanted_volumes=Incr-3669
muc1pro-backup-1-dir (50): next_vol.c:69-8021 find_next_vol_for_append: JobId=8021 PoolId=1, MediaType=FileIncr
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Append'  ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 2
muc1pro-backup-1-dir (40): sql_find.c:426-8021 item=2 got=0
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=0
muc1pro-backup-1-dir (50): next_vol.c:93-8021 after find_next_vol ok=0 index=2 InChanger=0 Vstat=Append
muc1pro-backup-1-dir (100): sql_find.c:419-8021 fnextvol=SELECT MediaId,VolumeName,VolJobs,VolFiles,VolBlocks,VolBytes,VolMounts,VolErrors,VolWrites,MaxVolBytes,VolCapacityBytes,MediaType,VolStatus,PoolId,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,Recycle,Slot,FirstWritten,LastWritten,InChanger,EndFile,EndBlock,LabelType,LabelDate,StorageId,Enabled,LocationId,RecycleCount,InitialWrite,ScratchPoolId,RecyclePoolId,VolReadTime,VolWriteTime,ActionOnPurge,EncryptionKey,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=1 AND MediaType='FileIncr' AND Enabled=1 AND VolStatus='Recycle'  AND Recycle=1 ORDER BY LastWritten ASC,MediaId LIMIT 1
muc1pro-backup-1-dir (40): sql_find.c:508-8021 Rtn numrows=1
muc1pro-backup-1-dir (20): recycle.c:40-8021 Find_next_vol MediaId=0
muc1pro-backup-1-dir (50): next_vol.c:100-8021 find_recycled_volume ok=1 FW=0
muc1pro-backup-1-dir (50): next_vol.c:188-8021 VolJobs=0 FirstWritten=0
muc1pro-backup-1-dir (50): next_vol.c:206-8021 return ok=1 find_next_vol
muc1pro-backup-1-dir (40): catreq.c:149-8021 find_media ok=1 idx=2 vol=
muc1pro-backup-1-dir (100): catreq.c:97-8021 Vol Info for bg1nc0502.2016-12-15_14.23.18_33: 1000 OK VolName= VolJobs=0 VolFiles=0 VolBlocks=0 VolBytes=0 VolMounts=0 VolErrors=0 VolWrites=0 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Recycle Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=0 EndFile=0 EndBlock=0 LabelType=0 MediaId=0 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
muc1pro-backup-1-dir (100): backup.c:656-8021 FDStatus=f
muc1pro-backup-1-dir (100): backup.c:685-8021 cancel=1 fd_ok=1 FDJS=102 JS=102 SDJS=82
muc1pro-backup-1-dir (100): backup.c:688-8021 fd_ok=1 FDJS=102 JS=102 SDJS=82
muc1pro-backup-1-dir (100): msgchan.c:393-8021 === End msg_thread. JobId=8021 usecnt=2
muc1pro-backup-1-dir (100): job.c:1734-8021 wstorage=DiskStorageIncr
muc1pro-backup-1-dir (100): job.c:1743-8021 wstore=DiskStorageIncr where=Pool resource
muc1pro-backup-1-dir (100): job.c:1371-8021 JobId=0 created Job=*JobCancel*.2016-12-15_14.23.21_34
muc1pro-backup-1-dir (50): job.c:1767-8021 wstore=DiskStorageIncr where=
muc1pro-backup-1-dir (100): sd_cmds.c:110-8021 bnet_connect to Storage daemon muc1pro-backup-1.adm.financial.com:9103
muc1pro-backup-1-dir (100): bsock.c:70-8021 All source addresses 
muc1pro-backup-1-dir (100): bsock_tcp.c:230-8021 Current host[ipv4;10.0.1.233;9103]  All host[ipv4;10.0.1.233;9103] 
muc1pro-backup-1-dir (100): bsock_tcp.c:153-8021 who=Storage daemon host=muc1pro-backup-1.adm.financial.com port=9103
muc1pro-backup-1-dir (100): cram-md5.c:123-8021 cram-get received: auth cram-md5 <681428558.1481808201@muc1pro-backup-1-sd> ssl=0
muc1pro-backup-1-dir (99): cram-md5.c:143-8021 sending resp to challenge: U5+umW1c98wl0EFw+7hwuA
muc1pro-backup-1-dir (50): cram-md5.c:75-8021 send: auth cram-md5 <1544737465.1481808201@muc1pro-backup-1-dir> ssl=0
muc1pro-backup-1-dir (50): cram-md5.c:94-8021 Authenticate OK zw+5E5JYf5+9xC/0I//EDB
muc1pro-backup-1-dir (50): sql_create.c:836-8021 db_create_file_record : no files
muc1pro-backup-1-dir (100): backup.c:740-8021 Enter backup_cleanup 69 E
muc1pro-backup-1-dir (100): backup.c:802-8021 Leave backup_cleanup()
muc1pro-backup-1-dir (50): job.c:528-8021 ======== End Job stat=f ==========