View Issue Details
| ID | Project | Category | View Status | Date Submitted | Last Update |
|---|---|---|---|---|---|
| 0000318 | bareos-core | director | public | 2014-07-11 13:06 | 2015-03-25 19:18 |
| Reporter | holtkamp | Assigned To | |||
| Priority | normal | Severity | major | Reproducibility | always |
| Status | closed | Resolution | fixed | ||
| Platform | Linux | OS | RHEL | OS Version | 6 |
| Product Version | 13.2.2 | ||||
| Summary | 0000318: copy jobs & cancel duplicates | ||||
| Description | Jobs are marked canceled in the DB that finished successfully. | ||||
| Steps To Reproduce | 1) "allow duplicate jobs = no" in a backup job directive 2) run job 3) after job finished, add a big file to the job 4) run job again 5) initiate copy job that would copy the first job while the second job is running (or waiting to start) -> a copy job produces two job entries the copy job management entry is good the prior job entry is canceled -> Copy job will complete (!!!), data IS copied -> the 2nd job entry that represents the copy is written to DB but marked as canceled At next copy job start it will be copied AGAIN | ||||
| Additional Information | If multiple jobs are selected for copy not all jobs are automatically canceled, i think only the first one. | ||||
| Tags | No tags attached. | ||||
 |
Please attach a debug output of the director lets say at level 100 or 200. This looks to be somewhat to big an effort to try to reproduce our self. Hopefully the debug gives an hint at what corner case this is. |
|
|
bareos-cancel-duplicate.txt (46,422 bytes)
After run copy:
Running Jobs:
Console connected at 14-Jul-14 15:04
JobId Level Name Status
======================================================================
2652 Increme backup.test.riege.de.2014-07-14_15.05.35_04 is running
2653 Full backup.test.riege.de.copy.2014-07-14_15.05.40_05 is waiting on max Client jobs
2654 Full backup.test.riege.de.copy.2014-07-14_15.05.40_06 is waiting on max Client jobs
2655 Increme backup.test.riege.de.2014-07-14_15.05.40_07 has been canceled
2656 Full backup.test.riege.de.copy.2014-07-14_15.05.40_08 is waiting on max Client jobs
2657 Increme backup.test.riege.de.2014-07-14_15.05.40_09 is waiting execution
2658 Full backup.test.riege.de.copy.2014-07-14_15.05.40_10 is waiting on max Client jobs
2659 Increme backup.test.riege.de.2014-07-14_15.05.40_11 is waiting execution
2660 Full backup.test.riege.de.copy.2014-07-14_15.05.40_12 is waiting on max Client jobs
2661 Increme backup.test.riege.de.2014-07-14_15.05.40_13 is waiting execution
2662 Full backup.test.riege.de.copy.2014-07-14_15.05.40_14 is waiting on max Client jobs
2663 Increme backup.test.riege.de.2014-07-14_15.05.40_15 is waiting execution
2664 Increme backup.test.riege.de.2014-07-14_15.05.40_16 is waiting execution
====
Job Enqueue:
14-Jul-2014 15:05:39 backup.test-dir: ua_run.c:1965-0 Using pool backup.test.riege.de
14-Jul-2014 15:05:39 backup.test-dir: ua_run.c:1983-0 Using next pool backup.test.riege.de
14-Jul-2014 15:05:39 backup.test-dir: job.c:1634-0 wstorage=backup.test.copy.filestorage
14-Jul-2014 15:05:39 backup.test-dir: job.c:1643-0 wstore=backup.test.copy.filestorage where=Job resource
14-Jul-2014 15:05:39 backup.test-dir: job.c:1667-0 wstore=backup.test.filestorage where=Pool resource
14-Jul-2014 15:05:39 backup.test-dir: job.c:1634-0 wstorage=library.test.filestorage
14-Jul-2014 15:05:39 backup.test-dir: job.c:1643-0 wstore=library.test.filestorage where=Storage from Run NextPool override
14-Jul-2014 15:05:40 backup.test-dir: ua_run.c:432-0 JobId=0 using pool backup.test.riege.de priority=10
14-Jul-2014 15:05:40 backup.test-dir: message.c:349-0 Copy message resource 24bbc78 to 7f05740101a8
14-Jul-2014 15:05:40 backup.test-dir: job.c:1290-0 JobId=0 created Job=backup.test.riege.de.copy.2014-07-14_15.05.40_05
14-Jul-2014 15:05:40 backup.test-dir: job.c:138-0 Open database
14-Jul-2014 15:05:40 backup.test-dir: sql_pooling.c:58-0 db_sql_get_non_pooled_connection allocating 1 new non pooled database connection to database bareos, backend type mysql
14-Jul-2014 15:05:40 backup.test-dir: cats_dynamic.c:55-0 db_init_database: Trying to find mapping of given interfacename mysql to mapping interfacename dbi, partly_compare = true
14-Jul-2014 15:05:40 backup.test-dir: cats_dynamic.c:55-0 db_init_database: Trying to find mapping of given interfacename mysql to mapping interfacename mysql, partly_compare = false
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:747-0 DB REopen bareos
14-Jul-2014 15:05:40 backup.test-dir: job.c:154-0 DB opened
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT ClientId,Uname FROM Client WHERE Name='backup.test.riege.de''
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 2 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: job.c:1160-0 Created Client backup.test.riege.de record 3
14-Jul-2014 15:05:40 backup.test-dir: job.c:190-0 Created job record JobId=2653 Name=backup.test.riege.de.copy.2014-07-14_15.05.40_05 Type=c Level=F
14-Jul-2014 15:05:40 backup.test-dir: dir_plugins.c:299-0 === enter new_plugins ===
14-Jul-2014 15:05:40 backup.test-dir: dir_plugins.c:301-0 No dir plugin list!
14-Jul-2014 15:05:40 backup.test-dir: job.c:1039-0 entering apply_pool_overrides()
14-Jul-2014 15:05:40 backup.test-dir: job.c:738-0 get_or_create_pool=backup.test.riege.de
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT PoolId,Name,NumVols,MaxVols,UseOnce,UseCatalog,AcceptAnyVolume,AutoPrune,Recycle,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,MaxVolBytes,PoolType,LabelType,LabelFormat,RecyclePoolId,ScratchPoolId,ActionOnPurge FROM Pool WHERE Pool.Name='backup.test.riege.de''
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 20 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT count(*) from Media WHERE PoolId=5'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 1 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: sql_get.c:663-0 Actual NumVols=11 Pool NumVols=11
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:138-0 Read pool=backup.test.riege.de (From Job resource)
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (2653,'2014-07-14 15:05:40','backup.test-dir JobId 2653: Warning: FileSet MD5 digest not found.\n\0')'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:484-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT FileSetId,CreateTime FROM FileSet WHERE FileSet='EmptySet' AND MD5='''
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 2 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: job.c:1197-0 Created FileSet EmptySet record 3
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:1302-0 copy selection pattern=backup.test.riege.de
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:1304-0 get uncopied jobs query=SELECT DISTINCT Job.JobId,Job.StartTime FROM Job,Pool WHERE Pool.Name = 'backup.test.riege.de' AND Pool.PoolId = Job.PoolId AND Job.Type = 'B' AND Job.JobStatus IN ('T','W') AND Job.jobBytes > 0 AND Job.JobId NOT IN (SELECT PriorJobId FROM Job WHERE Type IN ('B','C') AND Job.JobStatus IN ('T','W') AND PriorJobId != 0) ORDER by Job.StartTime
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:406-0 db_sql_query starts with SELECT DISTINCT Job.JobId,Job.StartTime FROM Job,Pool WHERE Pool.Name = 'backup.test.riege.de' AND Pool.PoolId = Job.PoolId AND Job.Type = 'B' AND Job.JobStatus IN ('T','W') AND Job.jobBytes > 0 AND Job.JobId NOT IN (SELECT PriorJobId FROM Job WHERE Type IN ('B','C') AND Job.JobStatus IN ('T','W') AND PriorJobId != 0) ORDER by Job.StartTime
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:416-0 db_sql_query succeeded. checking handler
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:753-0 dbid_hdlr count=1 Ids=7f0574011e10 2049
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:753-0 dbid_hdlr count=2 Ids=7f0574011e10 2049,2115
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:753-0 dbid_hdlr count=3 Ids=7f0574011e10 2049,2115,2181
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:753-0 dbid_hdlr count=4 Ids=7f0574011e10 2049,2115,2181,2380
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:753-0 dbid_hdlr count=5 Ids=7f0574011e10 2049,2115,2181,2380,2448
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:753-0 dbid_hdlr count=6 Ids=7f0574011e10 2049,2115,2181,2380,2448,2516
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:440-0 db_sql_query finished
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (2653,'2014-07-14 15:05:40','backup.test-dir JobId 2653: The following 6 JobIds were chosen to be copied: 2049,2115,2181,2380,2448,2516\n\0')'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:484-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:1112-0 Before loop count=6 ids=2049,2115,2181,2380,2448,2516
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:1120-0 getJobid_no=1 status=1 JobId=2049
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:1215-0 =============== Copying cmd=run job="backup.test.riege.de.copy" jobid=2049 ignoreduplicatecheck=yes
14-Jul-2014 15:05:40 backup.test-dir: ua_run.c:1965-0 Using pool backup.test.riege.de
14-Jul-2014 15:05:40 backup.test-dir: ua_run.c:1983-0 Using next pool backup.test.riege.de
14-Jul-2014 15:05:40 backup.test-dir: job.c:1634-0 wstorage=backup.test.copy.filestorage
14-Jul-2014 15:05:40 backup.test-dir: job.c:1643-0 wstore=backup.test.copy.filestorage where=Job resource
14-Jul-2014 15:05:40 backup.test-dir: job.c:1667-0 wstore=backup.test.filestorage where=Pool resource
14-Jul-2014 15:05:40 backup.test-dir: job.c:1634-0 wstorage=library.test.filestorage
14-Jul-2014 15:05:40 backup.test-dir: job.c:1643-0 wstore=library.test.filestorage where=Storage from Run NextPool override
14-Jul-2014 15:05:40 backup.test-dir: ua_run.c:432-0 JobId=0 using pool backup.test.riege.de priority=10
14-Jul-2014 15:05:40 backup.test-dir: message.c:349-0 Copy message resource 24bbc78 to 7f0574015478
14-Jul-2014 15:05:40 backup.test-dir: job.c:1290-0 JobId=0 created Job=backup.test.riege.de.copy.2014-07-14_15.05.40_06
14-Jul-2014 15:05:40 backup.test-dir: job.c:138-0 Open database
14-Jul-2014 15:05:40 backup.test-dir: sql_pooling.c:58-0 db_sql_get_non_pooled_connection allocating 1 new non pooled database connection to database bareos, backend type mysql
14-Jul-2014 15:05:40 backup.test-dir: cats_dynamic.c:55-0 db_init_database: Trying to find mapping of given interfacename mysql to mapping interfacename dbi, partly_compare = true
14-Jul-2014 15:05:40 backup.test-dir: cats_dynamic.c:55-0 db_init_database: Trying to find mapping of given interfacename mysql to mapping interfacename mysql, partly_compare = false
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:747-0 DB REopen bareos
14-Jul-2014 15:05:40 backup.test-dir: job.c:154-0 DB opened
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT ClientId,Uname FROM Client WHERE Name='backup.test.riege.de''
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 2 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: job.c:1160-0 Created Client backup.test.riege.de record 3
14-Jul-2014 15:05:40 backup.test-dir: job.c:190-0 Created job record JobId=2654 Name=backup.test.riege.de.copy.2014-07-14_15.05.40_06 Type=c Level=F
14-Jul-2014 15:05:40 backup.test-dir: dir_plugins.c:299-0 === enter new_plugins ===
14-Jul-2014 15:05:40 backup.test-dir: dir_plugins.c:301-0 No dir plugin list!
14-Jul-2014 15:05:40 backup.test-dir: job.c:1039-0 entering apply_pool_overrides()
14-Jul-2014 15:05:40 backup.test-dir: job.c:738-0 get_or_create_pool=backup.test.riege.de
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT PoolId,Name,NumVols,MaxVols,UseOnce,UseCatalog,AcceptAnyVolume,AutoPrune,Recycle,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,MaxVolBytes,PoolType,LabelType,LabelFormat,RecyclePoolId,ScratchPoolId,ActionOnPurge FROM Pool WHERE Pool.Name='backup.test.riege.de''
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 20 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT count(*) from Media WHERE PoolId=5'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 1 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: sql_get.c:663-0 Actual NumVols=11 Pool NumVols=11
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:138-0 Read pool=backup.test.riege.de (From Job resource)
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (2654,'2014-07-14 15:05:40','backup.test-dir JobId 2654: Warning: FileSet MD5 digest not found.\n\0')'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:484-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT FileSetId,CreateTime FROM FileSet WHERE FileSet='EmptySet' AND MD5='''
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 2 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: job.c:1197-0 Created FileSet EmptySet record 3
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:946-0 At Job start previous jobid=2049
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:1151-0 Previous jobid=2049
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT VolSessionId,VolSessionTime,PoolId,StartTime,EndTime,JobFiles,JobBytes,JobTDate,Job,JobStatus,Type,Level,ClientId,Name,PriorJobId,RealEndTime,JobId,FileSetId,SchedTime,RealEndTime,ReadBytes,HasBase,PurgedFiles FROM Job WHERE JobId=2049'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 23 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (2654,'2014-07-14 15:05:40','backup.test-dir JobId 2654: Copying using JobId=2049 Job=backup.test.riege.de.2014-07-04_23.05.01_33\n\0')'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:484-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:1166-0 Copying JobId=2654 using JobId=2049 Job=backup.test.riege.de.2014-07-04_23.05.01_33
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:158-0 Back from getJob_to_migrate JobId=2654
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT VolSessionId,VolSessionTime,PoolId,StartTime,EndTime,JobFiles,JobBytes,JobTDate,Job,JobStatus,Type,Level,ClientId,Name,PriorJobId,RealEndTime,JobId,FileSetId,SchedTime,RealEndTime,ReadBytes,HasBase,PurgedFiles FROM Job WHERE JobId=2049'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 23 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: sql_get.c:436-0 VolNam=SELECT VolumeName,MediaType,FirstIndex,LastIndex,StartFile,JobMedia.EndFile,StartBlock,JobMedia.EndBlock,Slot,StorageId,InChanger FROM JobMedia,Media WHERE JobMedia.JobId=2049 AND JobMedia.MediaId=Media.MediaId ORDER BY VolIndex,JobMediaId
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT VolumeName,MediaType,FirstIndex,LastIndex,StartFile,JobMedia.EndFile,StartBlock,JobMedia.EndBlock,Slot,StorageId,InChanger FROM JobMedia,Media WHERE JobMedia.JobId=2049 AND JobMedia.MediaId=Media.MediaId ORDER BY VolIndex,JobMediaId'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 11 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 3 rows
14-Jul-2014 15:05:40 backup.test-dir: sql_get.c:439-0 Num rows=3
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT Name from Storage WHERE StorageId=1'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 1 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT Name from Storage WHERE StorageId=1'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 1 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT Name from Storage WHERE StorageId=1'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 1 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (2654,'2014-07-14 15:05:40','backup.test-dir JobId 2654: Bootstrap records written to /var/lib/bareos/backup.test-dir.restore.1.bsr\n\0')'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:484-0 we have 1 rows
Storage="backup.test.filestorage"
Volume="backup.test.riege.de.0147"
MediaType="backup.test.filestorage"
Device="backup.test.filestorage-1"
VolSessionId=747
VolSessionTime=1403690585
VolAddr=264-999935939
FileIndex=1-70957
Count=70957
Storage="backup.test.filestorage"
Volume="backup.test.riege.de.0147"
MediaType="backup.test.filestorage"
Device="backup.test.filestorage-1"
VolSessionId=747
VolSessionTime=1403690585
VolAddr=999935940-1244023910
FileIndex=70957-70991
Count=35
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:187-0 JobId=2654: Current: Name=backup.test.riege.de.copy JobId=2654 Type=c Level=F
14-Jul-2014 15:05:40 backup.test-dir: job.c:1634-0 wstorage=backup.test.filestorage
14-Jul-2014 15:05:40 backup.test-dir: job.c:1643-0 wstore=backup.test.filestorage where=Job resource
14-Jul-2014 15:05:40 backup.test-dir: job.c:1290-0 JobId=0 created Job=backup.test.riege.de.2014-07-14_15.05.40_07
14-Jul-2014 15:05:40 backup.test-dir: job.c:138-0 Open database
14-Jul-2014 15:05:40 backup.test-dir: sql_pooling.c:58-0 db_sql_get_non_pooled_connection allocating 1 new non pooled database connection to database bareos, backend type mysql
14-Jul-2014 15:05:40 backup.test-dir: cats_dynamic.c:55-0 db_init_database: Trying to find mapping of given interfacename mysql to mapping interfacename dbi, partly_compare = true
14-Jul-2014 15:05:40 backup.test-dir: cats_dynamic.c:55-0 db_init_database: Trying to find mapping of given interfacename mysql to mapping interfacename mysql, partly_compare = false
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:747-0 DB REopen bareos
14-Jul-2014 15:05:40 backup.test-dir: job.c:154-0 DB opened
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT ClientId,Uname FROM Client WHERE Name='backup.test.riege.de''
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 2 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: job.c:1160-0 Created Client backup.test.riege.de record 3
14-Jul-2014 15:05:40 backup.test-dir: job.c:190-0 Created job record JobId=2655 Name=backup.test.riege.de.2014-07-14_15.05.40_07 Type=B Level=I
14-Jul-2014 15:05:40 backup.test-dir: dir_plugins.c:299-0 === enter new_plugins ===
14-Jul-2014 15:05:40 backup.test-dir: dir_plugins.c:301-0 No dir plugin list!
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT FileSetId,CreateTime FROM FileSet WHERE FileSet='FullSet' AND MD5='O8V3Sls15SlCn4/s7w/aNB''
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 2 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: job.c:1197-0 Created FileSet FullSet record 1
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT StartTime, Job FROM Job WHERE JobStatus IN ('T','W') AND Type='B' AND Level='F' AND Name='backup.test.riege.de' AND ClientId=3 AND FileSetId=1 ORDER BY StartTime DESC LIMIT 1'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 2 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT StartTime, Job FROM Job WHERE JobStatus IN ('T','W') AND Type='B' AND Level IN ('I','D','F') AND Name='backup.test.riege.de' AND ClientId=3 AND FileSetId=1 ORDER BY StartTime DESC LIMIT 1'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 2 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: sql_find.c:132-0 Got start time: 2014-07-13 23:05:02, job: backup.test.riege.de.2014-07-13_23.05.00_57
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT StartTime, Job FROM Job WHERE JobStatus IN ('T','W') AND Type='B' AND Level='F' AND Name='backup.test.riege.de' AND ClientId=3 AND FileSetId=1 ORDER BY StartTime DESC LIMIT 1'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 2 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: sql_find.c:183-0 Got start time: 2014-07-06 23:05:32
14-Jul-2014 15:05:40 backup.test-dir: job.c:957-0 have_full=1 do_full=0 now=1405343140 full_time=1404680732
14-Jul-2014 15:05:40 backup.test-dir: job.c:1032-0 Level=I last start time=2014-07-13 23:05:02 job=backup.test.riege.de.2014-07-13_23.05.00_57
14-Jul-2014 15:05:40 backup.test-dir: job.c:1039-0 entering apply_pool_overrides()
14-Jul-2014 15:05:40 backup.test-dir: job.c:738-0 get_or_create_pool=backup.test.riege.de
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT PoolId,Name,NumVols,MaxVols,UseOnce,UseCatalog,AcceptAnyVolume,AutoPrune,Recycle,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,MaxVolBytes,PoolType,LabelType,LabelFormat,RecyclePoolId,ScratchPoolId,ActionOnPurge FROM Pool WHERE Pool.Name='backup.test.riege.de''
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 20 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT count(*) from Media WHERE PoolId=5'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 1 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: sql_get.c:663-0 Actual NumVols=11 Pool NumVols=11
14-Jul-2014 15:05:40 backup.test-dir: job.c:1634-0 wstorage=backup.test.filestorage
14-Jul-2014 15:05:40 backup.test-dir: job.c:1643-0 wstore=backup.test.filestorage where=Pool resource
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:256-0 mig_jcr: Name=backup.test.riege.de JobId=2655 Type=B Level=F
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT PoolId,Name,NumVols,MaxVols,UseOnce,UseCatalog,AcceptAnyVolume,AutoPrune,Recycle,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,MaxVolBytes,PoolType,LabelType,LabelFormat,RecyclePoolId,ScratchPoolId,ActionOnPurge FROM Pool WHERE Pool.PoolId=5'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 20 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT count(*) from Media WHERE PoolId=5'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 1 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: sql_get.c:663-0 Actual NumVols=11 Pool NumVols=11
14-Jul-2014 15:05:40 backup.test-dir: job.c:738-0 get_or_create_pool=TL
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT PoolId,Name,NumVols,MaxVols,UseOnce,UseCatalog,AcceptAnyVolume,AutoPrune,Recycle,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,MaxVolBytes,PoolType,LabelType,LabelFormat,RecyclePoolId,ScratchPoolId,ActionOnPurge FROM Pool WHERE Pool.Name='TL''
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 20 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT count(*) from Media WHERE PoolId=27'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:473-0 we have 1 fields
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:475-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: sql_get.c:663-0 Actual NumVols=1 Pool NumVols=1
14-Jul-2014 15:05:40 backup.test-dir: job.c:1634-0 wstorage=library.test.filestorage
14-Jul-2014 15:05:40 backup.test-dir: job.c:1643-0 wstore=library.test.filestorage where=Storage from Pool's NextPool resource
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:362-0 Write pool=TL read rpool=backup.test.riege.de
14-Jul-2014 15:05:40 backup.test-dir: job.c:81-0 Add jrc to work queue
14-Jul-2014 15:05:40 backup.test-dir: ua_run.c:438-0 JobId=2654 NewJobId=2654 using pool TL priority=10
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (2653,'2014-07-14 15:05:40','backup.test-dir JobId 2653: Job queued. JobId=2654\n\0')'
14-Jul-2014 15:05:40 backup.test-dir: jobq.c:750-0 Rstore=backup.test.filestorage
14-Jul-2014 15:05:40 backup.test-dir: jobq.c:812-0 Inc rncj=2
14-Jul-2014 15:05:40 backup.test-dir: jobq.c:759-0 Wstore=library.test.filestorage
14-Jul-2014 15:05:40 backup.test-dir: jobq.c:762-0 Inc wncj=1
14-Jul-2014 15:05:40 backup.test-dir: jobq.c:837-0 Dec wncj=0
14-Jul-2014 15:05:40 backup.test-dir: jobq.c:826-0 Dec rncj=1
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:484-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:453-0 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (2653,'2014-07-14 15:05:40','backup.test-dir JobId 2653: Copying JobId 2654 started.\n\0')'
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:468-0 we have a result
14-Jul-2014 15:05:40 backup.test-dir: mysql.c:484-0 we have 1 rows
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:1133-0 Back from start_migration_job
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:1120-0 getJobid_no=2 status=1 JobId=2115
14-Jul-2014 15:05:40 backup.test-dir: migrate.c:1215-0 =============== Copying cmd=run job="backup.test.riege.de.copy" jobid=2115 ignoreduplicatecheck=yes
Job Start:
14-Jul-2014 15:12:11 backup.test-dir: job.c:334-2654 =====Start Job=========
14-Jul-2014 15:12:11 backup.test-dir: job.c:352-2654 Warning, job->RunScripts is empty
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:453-2654 sql_query starts with 'UPDATE Job SET JobStatus='R',Level='F',StartTime='2014-07-14 15:12:11',ClientId=3,JobTDate=1405343531,PoolId=27,FileSetId=3 WHERE JobId=2654'
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:12:11 backup.test-dir: runscript.c:149-2654 runscript: running all RUNSCRIPT object (BeforeJob) JobStatus=R
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:453-2654 sql_query starts with 'UPDATE Job SET JobStatus='R',Level='F',StartTime='2014-07-14 15:12:11',ClientId=3,JobTDate=1405343531,PoolId=27,FileSetId=3 WHERE JobId=2654'
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:453-2654 sql_query starts with 'SELECT VolSessionId,VolSessionTime,PoolId,StartTime,EndTime,JobFiles,JobBytes,JobTDate,Job,JobStatus,Type,Level,ClientId,Name,PriorJobId,RealEndTime,JobId,FileSetId,SchedTime,RealEndTime,ReadBytes,HasBase,PurgedFiles FROM Job WHERE JobId=2049'
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:473-2654 we have 23 fields
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:475-2654 we have 1 rows
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:453-2654 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (2654,'2014-07-14 15:12:11','backup.test-dir JobId 2654: Start Copying JobId 2654, Job=backup.test.riege.de.copy.2014-07-14_15.05.40_06\n\0')'
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:12:11 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:12:11 backup.test-dir: migrate.c:421-2654 Read store=backup.test.filestorage, write store=library.test.filestorage
14-Jul-2014 15:12:11 backup.test-dir: migrate.c:427-2654 Open connection with storage daemon
14-Jul-2014 15:12:11 backup.test-dir: sd_cmds.c:102-2654 bnet_connect to Storage daemon 10.11.6.32:9103
14-Jul-2014 15:12:11 backup.test-dir: bsock.c:168-2654 All source addresses
14-Jul-2014 15:12:11 backup.test-dir: bsock.c:244-2654 Current host[ipv4:10.11.6.32:9103] All host[ipv4:10.11.6.32:9103]
14-Jul-2014 15:12:11 backup.test-dir: bsock.c:150-2654 who=Storage daemon host=10.11.6.32 port=9103
14-Jul-2014 15:12:11 backup.test-dir: cram-md5.c:123-2654 cram-get received: auth cram-md5 <87259199.1405343531@backup.test-sd> ssl=0
14-Jul-2014 15:12:11 backup.test-dir: cram-md5.c:142-2654 sending resp to challenge: tR+AqnUrb+/Wl14nB//NyB
14-Jul-2014 15:12:11 backup.test-dir: cram-md5.c:71-2654 send: auth cram-md5 <1790374427.1405343531@backup.test-dir> ssl=0
14-Jul-2014 15:12:11 backup.test-dir: cram-md5.c:90-2654 Authenticate OK C94Mk5+Wt38zO5JepiZ3xA
14-Jul-2014 15:12:11 backup.test-dir: authenticate.c:163-2654 >stored: 1000 OK auth
14-Jul-2014 15:12:11 backup.test-dir: authenticate.c:170-2654 <stored: 3000 OK Hello
14-Jul-2014 15:12:11 backup.test-dir: msgchan.c:187-2654 Remainingquota: 0
14-Jul-2014 15:12:11 backup.test-dir: msgchan.c:198-2654 >stored: JobId=2654 job=backup.test.riege.de.copy.2014-07-14_15.05.40_06 job_name=backup.test.riege.de.copy client_name=backup.test.riege.de type=99 level=70 FileSet=EmptySet NoAttr=0 SpoolAttr=1 FileSetMD5=**Dummy** SpoolData=0 PreferMountedVols=1 SpoolSize=0 rerunning=0 VolSessionId=0 VolSessi
onTime=0 Quota=0 Protocol=0 BackupFormat=Native DumpLevel=0
14-Jul-2014 15:12:11 backup.test-dir: getmsg.c:135-2654 bget_dirmsg 91: 3000 OK Job SDid=2 SDtime=1405343129 Authorization=PPCN-EAEA-HPJH-IDNE-JNHH-GHKA-HKDF-CNPA
14-Jul-2014 15:12:11 backup.test-dir: msgchan.c:200-2654 <stored: 3000 OK Job SDid=2 SDtime=1405343129 Authorization=PPCN-EAEA-HPJH-IDNE-JNHH-GHKA-HKDF-CNPA
14-Jul-2014 15:12:11 backup.test-dir: msgchan.c:209-2654 sd_auth_key=PPCN-EAEA-HPJH-IDNE-JNHH-GHKA-HKDF-CNPA
14-Jul-2014 15:12:11 backup.test-dir: msgchan.c:113-2654 send_bootstrap_file_to_sd: /var/lib/bareos/backup.test-dir.restore.1.bsr
14-Jul-2014 15:12:12 backup.test-dir: getmsg.c:135-2654 bget_dirmsg 18: 3000 OK bootstrap
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:245-2654 Rstore=backup.test.filestorage
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:252-2654 rstore >stored: use storage=backup.test.filestorage media_type=backup.test.filestorage pool_name=backup.test.riege.de pool_type=Backup append=0 copy=0 stripe=0
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:259-2654 >stored: use device=backup.test.filestorage-1
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:259-2654 >stored: use device=backup.test.filestorage-2
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:259-2654 >stored: use device=backup.test.filestorage-3
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:259-2654 >stored: use device=backup.test.filestorage-4
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:259-2654 >stored: use device=backup.test.filestorage-5
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:259-2654 >stored: use device=backup.test.filestorage-6
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:259-2654 >stored: use device=backup.test.filestorage-7
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:259-2654 >stored: use device=backup.test.filestorage-8
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:259-2654 >stored: use device=backup.test.filestorage-9
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:259-2654 >stored: use device=backup.test.filestorage-10
14-Jul-2014 15:12:12 backup.test-dir: getmsg.c:135-2654 bget_dirmsg 52: 3000 OK use device device=backup.test.filestorage-1
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:265-2654 <stored: 3000 OK use device device=backup.test.filestorage-1
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:453-2654 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (2654,'2014-07-14 15:12:12','backup.test-dir JobId 2654: Using Device \"backup.test.filestorage-1\" to read.\n\0')'
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:290-2654 wstore >stored: use storage=library.test.filestorage media_type=library.test.filestorage pool_name=TL pool_type=Backup append=1 copy=0 stripe=0
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:297-2654 >stored: use device=library.test.filestorage-1
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:297-2654 >stored: use device=library.test.filestorage-2
14-Jul-2014 15:12:12 backup.test-dir: getmsg.c:135-2654 bget_dirmsg 121: CatReq Job=backup.test.riege.de.copy.2014-07-14_15.05.40_06 FindMedia=1 pool_name=TL media_type=library.test.filestorage
14-Jul-2014 15:12:12 backup.test-dir: catreq.c:122-2654 catreq CatReq Job=backup.test.riege.de.copy.2014-07-14_15.05.40_06 FindMedia=1 pool_name=TL media_type=library.test.filestorage
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:453-2654 sql_query starts with 'SELECT PoolId,Name,NumVols,MaxVols,UseOnce,UseCatalog,AcceptAnyVolume,AutoPrune,Recycle,VolRetention,VolUseDuration,MaxVolJobs,MaxVolFiles,MaxVolBytes,PoolType,LabelType,LabelFormat,RecyclePoolId,ScratchPoolId,ActionOnPurge FROM Pool WHERE Pool.Name='TL''
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:473-2654 we have 20 fields
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:475-2654 we have 1 rows
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:453-2654 sql_query starts with 'SELECT count(*) from Media WHERE PoolId=27'
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:473-2654 we have 1 fields
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:475-2654 we have 1 rows
14-Jul-2014 15:12:12 backup.test-dir: sql_get.c:663-2654 Actual NumVols=1 Pool NumVols=1
14-Jul-2014 15:12:12 backup.test-dir: next_vol.c:64-2654 find_next_vol_for_append: JobId=2654 PoolId=27, MediaType=library.test.filestorage
14-Jul-2014 15:12:12 backup.test-dir: sql_find.c:374-2654 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 FROM Media WHERE PoolId=27 AND MediaType='library.test.filestorage' AND Enabled=1 AND VolStatus='Append' ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 1
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:453-2654 sql_query starts with '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 FROM Media WHERE PoolId=27 AND MediaType='library.test.filestorage' AND Enabled=1 AND VolStatus='Append' ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 1'
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:473-2654 we have 38 fields
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:475-2654 we have 1 rows
14-Jul-2014 15:12:12 backup.test-dir: sql_find.c:451-2654 Rtn numrows=1
14-Jul-2014 15:12:12 backup.test-dir: next_vol.c:176-2654 VolJobs=1 FirstWritten=1405338932
14-Jul-2014 15:12:12 backup.test-dir: next_vol.c:264-2654 Vol=library.test.0869 expired=0
14-Jul-2014 15:12:12 backup.test-dir: next_vol.c:191-2654 return ok=1 find_next_vol
14-Jul-2014 15:12:12 backup.test-dir: catreq.c:144-2654 find_media ok=1 idx=1 vol=library.test.0869
14-Jul-2014 15:12:12 backup.test-dir: catreq.c:97-2654 Vol Info for backup.test.riege.de.copy.2014-07-14_15.05.40_06: 1000 OK VolName=library.test.0869 VolJobs=1 VolFiles=0 VolBlocks=0 VolBytes=239 VolMounts=1 VolErrors=0 VolWrites=1 MaxVolBytes=4000000000 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=27 EndFile=0 EndBlock=0 LabelType=0 MediaId=869 EncryptionKey=
14-Jul-2014 15:12:12 backup.test-dir: catreq.c:360-2654 >CatReq response: 1000 OK VolName=library.test.0869 VolJobs=1 VolFiles=0 VolBlocks=0 VolBytes=239 VolMounts=1 VolErrors=0 VolWrites=1 MaxVolBytes=4000000000 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=27 EndFile=0 EndBlock=0 LabelType=0 MediaId=869 EncryptionKey=
14-Jul-2014 15:12:12 backup.test-dir: catreq.c:361-2654 Leave catreq jcr 0x740132d8
14-Jul-2014 15:12:12 backup.test-dir: getmsg.c:135-2654 bget_dirmsg 53: 3000 OK use device device=library.test.filestorage-1
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:303-2654 <stored: 3000 OK use device device=library.test.filestorage-1
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:453-2654 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (2654,'2014-07-14 15:12:12','backup.test-dir JobId 2654: Using Device \"library.test.filestorage-1\" to write.\n\0')'
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:12:12 backup.test-dir: migrate.c:445-2654 Storage daemon connection OK
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:453-2654 sql_query starts with 'UPDATE Job SET JobStatus='R',Level='F',StartTime='2014-07-14 15:12:12',ClientId=3,JobTDate=1405343532,PoolId=27,FileSetId=3 WHERE JobId=2654'
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:453-2654 sql_query starts with 'UPDATE Job SET JobStatus='A',Level='F',StartTime='2014-07-14 15:12:12',ClientId=3,JobTDate=1405343532,PoolId=27,FileSetId=1 WHERE JobId=2655'
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:12:12 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:12:12 backup.test-dir: migrate.c:567-2654 mig_jcr: Name=backup.test.riege.de JobId=2655 Type=B Level=F
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:341-2654 Start SD msg_thread.
14-Jul-2014 15:12:12 backup.test-dir: msgchan.c:398-2654 Start msg_thread loop
Job End:
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:484-2654 we have 70991 rows
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:453-2654 sql_query starts with 'DROP TABLE batch'
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:484-2654 we have 0 rows
14-Jul-2014 15:14:20 backup.test-dir: migrate.c:1438-2654 Enter migrate_cleanup 84 T
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:453-2654 sql_query starts with 'UPDATE Job SET JobStatus='T',EndTime='2014-07-14 15:14:20',ClientId=3,JobBytes=0,ReadBytes=0,JobFiles=0,JobErrors=0,VolSessionId=2,VolSessionTime=1405343129,PoolId=27,FileSetId=3,JobTDate=1405343660,RealEndTime='2014-07-14 15:14:20',PriorJobId=0,HasBase=0,PurgedFiles=0 WHERE JobId=2654'
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:453-2654 sql_query starts with 'UPDATE Job SET JobStatus='A',EndTime='2014-07-14 15:14:20',ClientId=3,JobBytes=1239787239,ReadBytes=0,JobFiles=70991,JobErrors=1,VolSessionId=2,VolSessionTime=1405343129,PoolId=27,FileSetId=1,JobTDate=1405343660,RealEndTime='2014-07-14 15:14:20',PriorJobId=2049,HasBase=0,PurgedFiles=0 WHERE JobId=2655'
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:453-2654 sql_query starts with 'UPDATE Job SET StartTime='2014-07-04 23:05:04',EndTime='2014-07-05 00:36:25',JobTDate=1404513385 WHERE JobId=2655'
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:453-2654 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText ) SELECT 2655, Time, LogText FROM Log WHERE JobId=2049'
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:484-2654 we have 20 rows
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:453-2654 sql_query starts with 'UPDATE Job SET Type='C' WHERE JobId=2655'
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:453-2654 sql_query starts with 'SELECT VolSessionId,VolSessionTime,PoolId,StartTime,EndTime,JobFiles,JobBytes,JobTDate,Job,JobStatus,Type,Level,ClientId,Name,PriorJobId,RealEndTime,JobId,FileSetId,SchedTime,RealEndTime,ReadBytes,HasBase,PurgedFiles FROM Job WHERE JobId=2654'
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:473-2654 we have 23 fields
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:475-2654 we have 1 rows
14-Jul-2014 15:14:20 backup.test-dir: sql_get.c:378-2654 VolNam=SELECT VolumeName,MAX(VolIndex) FROM JobMedia,Media WHERE JobMedia.JobId=2655 AND JobMedia.MediaId=Media.MediaId GROUP BY VolumeName ORDER BY 2 ASC
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:453-2654 sql_query starts with 'SELECT VolumeName,MAX(VolIndex) FROM JobMedia,Media WHERE JobMedia.JobId=2655 AND JobMedia.MediaId=Media.MediaId GROUP BY VolumeName ORDER BY 2 ASC'
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:473-2654 we have 2 fields
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:475-2654 we have 1 rows
14-Jul-2014 15:14:20 backup.test-dir: sql_get.c:382-2654 Num rows=1
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:453-2654 sql_query starts with '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 FROM Media WHERE VolumeName='library.test.0870''
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:473-2654 we have 38 fields
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:475-2654 we have 1 rows
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:453-2654 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (2654,'2014-07-14 15:14:20','backup.test-dir JobId 2654: Bareos backup.test-dir 13.2.2 (12Nov13):\n Build OS: x86_64-unknown-linux-gnu redhat Red Hat Enterprise Linux Server release 6.3 (Santiago)\n Prev Backup JobId: 2049\n Prev Backup Job: backup.test.riege.de.2014-07-04_23.05.01_33\n New Backup JobId: 2655\n Current JobId: 2654\n Current Job: backup.test.riege.de.copy.2014-07-14_15.05.40_06\n Backup Level: Full\n Client: backup.test.riege.de\n FileSet: \"EmptySet\" 2014-06-24 12:04:31\n Read Pool: \"backup.test.riege.de\" (From Job resource)\n Read Storage: \"backup.test.filestorage\" (From Pool resource)\n Write Pool: \"TL\" (From Job Pool\'s NextPool resource)\n Write Storage: \"library.test.filestorage\" (From Storage from Pool\'s NextPool resource)\n Next Pool: \"TL\" (From Job Pool\'s NextPool resource)\n Catalog: \"backup.test.base.catalog\" (From Client resource)\n Start time: 14-Jul-2014 15:12:12\n End time: 14-Jul-2014 15:14:20\n Elapsed time: 2 mins 8 secs\n Priority: 10\n SD Files Written: 70,991\n SD Bytes Written: 1,239,787,239 (1.239 GB)\n Rate: 9685.8 KB/s\n Volume name(s): library.test.0870\n Volume Session Id: 2\n Volume Session Time: 1405343129\n Last Volume Bytes: 1,244,023,886 (1.244 GB)\n SD Errors: 0\n SD termination status: OK\n Termination: Copying OK\n\n\0')'
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:468-2654 we have a result
14-Jul-2014 15:14:20 backup.test-dir: mysql.c:484-2654 we have 1 rows
14-Jul-2014 15:14:20 backup.test-dir: migrate.c:1713-2654 Leave migrate_cleanup()
14-Jul-2014 15:14:20 backup.test-dir: ua_prune.c:429-2654 now=1405343660 period=8640000 JobTDate=1396703660
|
|
|
I was able to reproduce this on our test server. I put the relevant log parts into the attached files. If you need more output i can upload the complete log and i even have an sql query log should you need it. I tried to analyze the logs but was unable to find any hint as to what causes this :( |
|
|
It seems by the time that it starts the copying is already marked as being canceled: 14-Jul-2014 15:12:12 backup.test-dir: mysql.c:453-2654 sql_query starts with 'UPDATE Job SET JobStatus='A',Level='F',StartTime='2014-07-14 15:12:12',ClientId=3,JobTDate=1405343532,PoolId=27,FileSetId=1 WHERE JobId=2655' 14-Jul-2014 15:12:12 backup.test-dir: mysql.c:468-2654 we have a result 14-Jul-2014 15:12:12 backup.test-dir: mysql.c:484-2654 we have 1 rows 14-Jul-2014 15:12:12 backup.test-dir: migrate.c:567-2654 mig_jcr: Name=backup.test.riege.de JobId=2655 Type=B Level=F That is line 560: db_update_job_start_record where it seems the mig_jcr already has a JS_Canceled Job state. As this has higher priority it will was not set to JS_Running in line 555. I think is something that the code never expects so that is why it continues. Do you have something like maximum run times on your Jobs ? I wouldn't be surprised if the canceling comes from a run timer going off thinking the copy of the job runs outside the backup window defined. I already added quite some logic to ignore the normal cancel logic for copy and migrate jobs but the code is kind of naive in that sense. |
|
|
B.T.W. we are on full holiday mode so things won't get much attention at the moment also while we are finishing up all queued code for 14.2.0 for release later this year. |
|
|
Have a nice vacation then :) FYI: No maximum run times or other cancel conditions are configured. When i remove "Allow duplicate Jobs = no" from the original JobDef (it is NOT in the Copy JobDef) this does not happen. Maybe that helps. And yay, 14.2 :) |
|
|
Its not so much that I'm on vacation but most others are :-) But your last hint did spur some idea. Could you try the attached patch ? It moves some settings to the first calls after creating the mig_jcr hope this solves the problem if not it has to wait. |
|
|
Ok i tried it with the patch and it goes kaboom. bareos-dir.log and gdb traceback from the crash attached. |
|
|
Ah stupid mistake setting a member of mig_jcr->res.job when that pointer is set in set_jcr_defaults() so need to move the two commands just after that not before that but before call to setup_job(). |
|
|
Attached new patch which what I actually wanted to implement but did wrong. Please give it a try and let me know where (if it does) fails. For a good analyze I'm mostly interested in the traceback logs not so much the bactrace ones as that is some internal state while traceback is a backtrace from within a real debugger so gives some programming stacks. |
|
|
Sadly that did not fix the problem. But i poked a bit at the code (i don´t know C but i can somewhat read it and copy/paste debug lines ;) ) and found where the job gets canceled. job.c at line 887 Does that help? Attached the short part from job creation to the cancel statement. The Job canceled line is from my bold copy & paste attempts. |
|
|
job-cancel-point.txt (2,803 bytes)
16-Jul-2014 15:05:33 backup.test-dir: job.c:190-0 Created job record JobId=3011 Name=cc.c2k.dev.riege.de.2014-07-16_15.05.33_06 Type=B Level=I
16-Jul-2014 15:05:33 backup.test-dir: dir_plugins.c:299-0 === enter new_plugins ===
16-Jul-2014 15:05:33 backup.test-dir: dir_plugins.c:301-0 No dir plugin list!
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT FileSetId,CreateTime FROM FileSet WHERE FileSet='FullSet' AND MD5='O8V3Sls15SlCn4/s7w/aNB''
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:468-0 we have a result
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:473-0 we have 2 fields
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:475-0 we have 1 rows
16-Jul-2014 15:05:33 backup.test-dir: job.c:1214-0 Created FileSet FullSet record 1
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT StartTime, Job FROM Job WHERE JobStatus IN ('T','W') AND Type='B' AND Level='F' AND Name='cc.c2k.dev.riege.de' AND ClientId=29 AND FileSetId=1 ORDER BY StartTime DESC LIMIT 1'
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:468-0 we have a result
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:473-0 we have 2 fields
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:475-0 we have 1 rows
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT StartTime, Job FROM Job WHERE JobStatus IN ('T','W') AND Type='B' AND Level IN ('I','D','F') AND Name='cc.c2k.dev.riege.de' AND ClientId=29 AND FileSetId=1 ORDER BY StartTime DESC LIMIT 1'
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:468-0 we have a result
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:473-0 we have 2 fields
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:475-0 we have 1 rows
16-Jul-2014 15:05:33 backup.test-dir: sql_find.c:132-0 Got start time: 2014-07-16 14:21:32, job: cc.c2k.dev.riege.de.2014-07-16_14.21.30_03
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:453-0 sql_query starts with 'SELECT StartTime, Job FROM Job WHERE JobStatus IN ('T','W') AND Type='B' AND Level='F' AND Name='cc.c2k.dev.riege.de' AND ClientId=29 AND FileSetId=1 ORDER BY StartTime DESC LIMIT 1'
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:468-0 we have a result
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:473-0 we have 2 fields
16-Jul-2014 15:05:33 backup.test-dir: mysql.c:475-0 we have 1 rows
16-Jul-2014 15:05:33 backup.test-dir: sql_find.c:183-0 Got start time: 2014-07-06 23:05:43
16-Jul-2014 15:05:33 backup.test-dir: job.c:974-0 have_full=1 do_full=0 now=1405515933 full_time=1404680743
16-Jul-2014 15:05:33 backup.test-dir: job.c:1049-0 Level=I last start time=2014-07-16 14:21:32 job=cc.c2k.dev.riege.de.2014-07-16_14.21.30_03
16-Jul-2014 15:05:33 backup.test-dir: job.c:1056-0 entering apply_pool_overrides()
16-Jul-2014 15:05:33 backup.test-dir: job.c:888-0 Job Canceled = 3011 |
|
|
It shouldn't come to that point. e.g. it should have exited at the top of the cancel_job function. Could you try the following change ? diff --git a/src/dird/migrate.c b/src/dird/migrate.c index 104eb9e..3371673 100644 --- a/src/dird/migrate.c +++ b/src/dird/migrate.c @@ -255,7 +255,7 @@ bool do_migration_init(JCR *jcr) /* * Don't check for duplicates on migration and copy jobs */ - mig_jcr->res.job->IgnoreDuplicateJobChecking = true; + mig_jcr->IgnoreDuplicateJobChecking = true; if (!setup_job(mig_jcr, true)) { Jmsg(jcr, M_FATAL, 0, _("setup job failed.\n")); |
|
|
*DINGDINGDING* We have a winner!!! That fixes it :) Tried it 5 times in a row, looking good. |
|
|
Yes brains must be in holiday mode. When looking at the cancel_job function I saw that is uses a different var to evaluate if it needs to check anything duplicate on this Job. So the first analyzes still stands e.g. the settings need to move before the setup_job but we really should set the right flags. This is only a problem for the one Job that is handled by the Job that does the actual selection so your idea that it only happened for one Job out of a set of copies is also right. This one has been in for quite some time. Interesting no one ever fixed it before. |
|
|
Fix committed to bareos bareos-13.2 branch with changesetid 1928. |
|
|
Fix committed to bareos master branch with changesetid 1929. |
|
|
Fix committed to bareos bareos-12.4 branch with changesetid 1930. |
|
|
Fix committed to bareos2015 bareos-14.2 branch with changesetid 4719. |
|
|
Due to the reimport of the Github repository to bugs.bareos.org, the status of some tickets have been changed. These tickets will be closed again. Sorry for the noise. |
|
bareos: bareos-13.2 b5231938 2014-07-14 19:20 Ported: N/A Details Diff |
Move special flags for mig_jcr. We need to set the duplicate checking and maxtime check flags on a mig_jcr to disables as soon as we instantiated the mig_jcr as setup_job() already evaluates if the Job is a duplicate. Fixes 0000318: copy jobs & cancel duplicates |
Affected Issues 0000318 |
|
| mod - src/dird/migrate.c | Diff File | ||
|
bareos: master b0500079 2014-07-14 19:20 Ported: N/A Details Diff |
Move special flags for mig_jcr. We need to set the duplicate checking and maxtime check flags on a mig_jcr to disables as soon as we instantiated the mig_jcr as setup_job() already evaluates if the Job is a duplicate. Fixes 0000318: copy jobs & cancel duplicates |
Affected Issues 0000318 |
|
| mod - src/dird/migrate.c | Diff File | ||
|
bareos: bareos-12.4 6b0148c0 2014-07-14 19:20 Ported: N/A Details Diff |
Move special flags for mig_jcr. We need to set the duplicate checking and maxtime check flags on a mig_jcr to disables as soon as we instantiated the mig_jcr as setup_job() already evaluates if the Job is a duplicate. Fixes 0000318: copy jobs & cancel duplicates |
Affected Issues 0000318 |
|
| mod - src/dird/migrate.c | Diff File | ||
|
bareos2015: bareos-12.4 40673f5d 2014-07-14 21:20 Ported: N/A Details Diff |
Move special flags for mig_jcr. We need to set the duplicate checking and maxtime check flags on a mig_jcr to disables as soon as we instantiated the mig_jcr as setup_job() already evaluates if the Job is a duplicate. Fixes 0000318: copy jobs & cancel duplicates |
Affected Issues 0000318 |
|
| mod - src/dird/migrate.c | Diff File | ||
|
bareos2015: bareos-13.2 53cc7d8d 2014-07-14 21:20 Ported: N/A Details Diff |
Move special flags for mig_jcr. We need to set the duplicate checking and maxtime check flags on a mig_jcr to disables as soon as we instantiated the mig_jcr as setup_job() already evaluates if the Job is a duplicate. Fixes 0000318: copy jobs & cancel duplicates |
Affected Issues 0000318 |
|
| mod - src/dird/migrate.c | Diff File | ||
|
bareos2015: bareos-14.2 546d54ee 2014-07-14 21:20 Ported: N/A Details Diff |
Move special flags for mig_jcr. We need to set the duplicate checking and maxtime check flags on a mig_jcr to disables as soon as we instantiated the mig_jcr as setup_job() already evaluates if the Job is a duplicate. Fixes 0000318: copy jobs & cancel duplicates |
Affected Issues 0000318 |
|
| mod - src/dird/migrate.c | Diff File | ||
| Date Modified | Username | Field | Change |
|---|---|---|---|
| 2014-07-11 13:06 | holtkamp | New Issue | |
| 2014-07-11 21:02 | mvwieringen | Note Added: 0000916 | |
| 2014-07-11 21:02 | mvwieringen | Status | new => feedback |
| 2014-07-14 17:15 | holtkamp | File Added: bareos-cancel-duplicate.txt | |
| 2014-07-14 17:18 | holtkamp | Note Added: 0000917 | |
| 2014-07-14 17:18 | holtkamp | Status | feedback => new |
| 2014-07-14 18:08 | mvwieringen | Note Added: 0000918 | |
| 2014-07-14 18:10 | mvwieringen | Note Added: 0000919 | |
| 2014-07-14 18:10 | mvwieringen | Status | new => feedback |
| 2014-07-14 18:28 | holtkamp | Note Added: 0000920 | |
| 2014-07-14 18:28 | holtkamp | Status | feedback => new |
| 2014-07-14 19:19 | mvwieringen | Note Added: 0000921 | |
| 2014-07-14 19:19 | mvwieringen | Status | new => feedback |
| 2014-07-14 19:19 | mvwieringen | File Added: bug-318.patch | |
| 2014-07-15 14:22 | holtkamp | Note Added: 0000922 | |
| 2014-07-15 14:22 | holtkamp | Status | feedback => new |
| 2014-07-15 14:22 | holtkamp | File Added: bareos-dir-crashlog.txt | |
| 2014-07-15 14:22 | holtkamp | File Added: backup.test-dir.30450.bactrace | |
| 2014-07-15 21:10 | mvwieringen | File Deleted: bareos-dir-crashlog.txt | |
| 2014-07-15 21:10 | mvwieringen | File Deleted: backup.test-dir.30450.bactrace | |
| 2014-07-15 21:10 | mvwieringen | File Deleted: bug-318.patch | |
| 2014-07-15 21:12 | mvwieringen | Note Added: 0000923 | |
| 2014-07-15 21:12 | mvwieringen | File Added: 0001-Move-special-flags-for-mig_jcr.patch | |
| 2014-07-15 21:15 | mvwieringen | Note Added: 0000924 | |
| 2014-07-15 21:15 | mvwieringen | Status | new => feedback |
| 2014-07-16 15:13 | holtkamp | Note Added: 0000925 | |
| 2014-07-16 15:13 | holtkamp | Status | feedback => new |
| 2014-07-16 15:13 | holtkamp | File Added: job-cancel-point.txt | |
| 2014-07-16 15:32 | mvwieringen | Note Added: 0000926 | |
| 2014-07-16 15:33 | mvwieringen | Status | new => feedback |
| 2014-07-16 15:56 | holtkamp | Note Added: 0000927 | |
| 2014-07-16 15:56 | holtkamp | Status | feedback => new |
| 2014-07-16 16:07 | mvwieringen | Note Added: 0000928 | |
| 2014-07-16 16:11 | mvwieringen | File Deleted: 0001-Move-special-flags-for-mig_jcr.patch | |
| 2014-07-16 16:12 | mvwieringen | Changeset attached | => bareos bareos-13.2 b5231938 |
| 2014-07-16 16:12 | mvwieringen | Note Added: 0000930 | |
| 2014-07-16 16:12 | mvwieringen | Assigned To | => mvwieringen |
| 2014-07-16 16:12 | mvwieringen | Status | new => resolved |
| 2014-07-16 16:12 | mvwieringen | Resolution | open => fixed |
| 2014-07-16 22:17 | mvwieringen | Changeset attached | => bareos master b0500079 |
| 2014-07-16 22:17 | mvwieringen | Note Added: 0000931 | |
| 2014-07-17 10:18 | mvwieringen | Changeset attached | => bareos bareos-12.4 6b0148c0 |
| 2014-07-17 10:18 | mvwieringen | Note Added: 0000932 | |
| 2014-09-29 11:19 |
|
Assigned To | mvwieringen => |
| 2014-09-29 11:19 |
|
Status | resolved => closed |
| 2015-03-25 16:51 | mvwieringen | Changeset attached | => bareos2015 bareos-12.4 40673f5d |
| 2015-03-25 16:51 | mvwieringen | Changeset attached | => bareos2015 bareos-13.2 53cc7d8d |
| 2015-03-25 16:51 | mvwieringen | Changeset attached | => bareos2015 bareos-14.2 546d54ee |
| 2015-03-25 16:51 | mvwieringen | Note Added: 0001344 | |
| 2015-03-25 16:51 | mvwieringen | Status | closed => resolved |
| 2015-03-25 19:18 | joergs | Note Added: 0001500 | |
| 2015-03-25 19:18 | joergs | Status | resolved => closed |
