bareos-dir (100): ua_run.c:186-0 rerun cmdline=run job="backup-frontend-00-int" level="Incremental" client="frontend-00-int-fd" pool="Incremental" fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:185-0 Next arg=run job="backup-frontend-00-int" level="Incremental" client="frontend-00-int-fd" pool="Incremental" fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:209-0 End arg=run next=job="backup-frontend-00-int" level="Incremental" client="frontend-00-int-fd" pool="Incremental" fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:185-0 Next arg=job="backup-frontend-00-int" level="Incremental" client="frontend-00-int-fd" pool="Incremental" fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:209-0 End arg=job=backup-frontend-00-int next=level="Incremental" client="frontend-00-int-fd" pool="Incremental" fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:185-0 Next arg=level="Incremental" client="frontend-00-int-fd" pool="Incremental" fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:209-0 End arg=level=Incremental next=client="frontend-00-int-fd" pool="Incremental" fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:185-0 Next arg=client="frontend-00-int-fd" pool="Incremental" fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:209-0 End arg=client=frontend-00-int-fd next=pool="Incremental" fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:185-0 Next arg=pool="Incremental" fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:209-0 End arg=pool=Incremental next=fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:185-0 Next arg=fileset="FrontEnd" when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:209-0 End arg=fileset=FrontEnd next=when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:185-0 Next arg=when="2017-07-17 15:12:29" yes bareos-dir (900): scan.c:209-0 End arg=when=2017-07-17 15:12:29 next=yes bareos-dir (900): scan.c:185-0 Next arg=yes bareos-dir (900): scan.c:209-0 End arg=yes next= bareos-dir (900): scan.c:185-0 Next arg= bareos-dir (900): scan.c:209-0 End arg= next= bareos-dir (800): ua_run.c:1787-0 Doing arg 1 = job bareos-dir (800): ua_run.c:1802-0 Got keyword=job bareos-dir (800): ua_run.c:1787-0 Doing arg 2 = level bareos-dir (800): ua_run.c:1802-0 Got keyword=level bareos-dir (800): ua_run.c:1787-0 Doing arg 3 = client bareos-dir (800): ua_run.c:1802-0 Got keyword=client bareos-dir (800): ua_run.c:1787-0 Doing arg 4 = pool bareos-dir (800): ua_run.c:1802-0 Got keyword=pool bareos-dir (800): ua_run.c:1787-0 Doing arg 5 = fileset bareos-dir (800): ua_run.c:1802-0 Got keyword=fileset bareos-dir (800): ua_run.c:1787-0 Doing arg 6 = when bareos-dir (800): ua_run.c:1802-0 Got keyword=when bareos-dir (800): ua_run.c:1787-0 Doing arg 7 = yes bareos-dir (800): ua_run.c:1802-0 Got keyword=yes bareos-dir (800): ua_run.c:2070-0 Done scan. bareos-dir (800): ua_run.c:2083-0 Using catalog=*None* bareos-dir (800): ua_run.c:2094-0 Found job=backup-frontend-00-int bareos-dir (100): ua_run.c:2118-0 Using pool Incremental bareos-dir (800): ua_run.c:2165-0 Using storage=bs-00-inc bareos-dir (800): ua_run.c:2186-0 Using client=frontend-00-int-fd bareos-dir (800): ua_run.c:2207-0 Using restore client=frontend-00-int-fd bareos-dir (800): jcr.c:1005-0 set_jcr_job_status(*System*, C) bareos-dir (800): jcr.c:1016-0 OnEntry JobStatus= newJobstatus=C bareos-dir (800): jcr.c:1026-0 Set new stat. old: ,0 new: C,0 bareos-dir (800): jcr.c:1031-0 leave setJobStatus old= new=C bareos-dir (100): storage.c:145-0 wstorage=bs-00-inc bareos-dir (100): storage.c:154-0 wstore=bs-00-inc where=Pool resource bareos-dir (50): storage.c:181-0 wstore=bs-00-inc where=Pool resource bareos-dir (100): ua_run.c:514-0 JobId=0 using pool Incremental priority=10 bareos-dir (900): ua_run.c:515-0 Running a job; its spool_data = 0 bareos-dir (250): message.c:349-0 Copy message resource 734308 to 7ff63000f4d8 bareos-dir (100): job.c:1459-0 JobId=0 created Job=backup-frontend-00-int.2017-07-17_15.12.29_09 bareos-dir (800): jcr.c:1005-0 set_jcr_job_status(backup-frontend-00-int.2017-07-17_15.12.29_09, C) bareos-dir (800): jcr.c:1016-0 OnEntry JobStatus=C newJobstatus=C bareos-dir (800): jcr.c:1026-0 Set new stat. old: C,0 new: C,0 bareos-dir (100): job.c:141-0 Open database bareos-dir (100): sql_pooling.c:60-0 db_sql_get_non_pooled_connection allocating 1 new non pooled database connection to database bareos, backend type postgresql bareos-dir (100): cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename dbi, partly_compare = true bareos-dir (100): cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename mysql, partly_compare = false bareos-dir (100): cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename postgresql, partly_compare = false bareos-dir (100): postgresql.c:1223-0 DB REopen bareos bareos-dir (150): job.c:158-0 DB opened bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SELECT ClientId,Uname FROM Client WHERE Name='frontend-00-int-fd'' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 2 fields bareos-dir (500): postgresql.c:658-0 we have 1 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:758-0 we need space for 16 bytes bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '15' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '1' has value '16.2.4 (01Jul16) x86_64-pc-linux-gnu,debian,Debian GNU/Linux 8.0 (jessie),Debian_8.0,x86_64' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff630005618 bareos-dir (100): job.c:1319-0 Created Client frontend-00-int-fd record 15 bareos-dir (500): postgresql.c:622-0 sql_query starts with 'INSERT INTO Job (Job,Name,Type,Level,JobStatus,SchedTime,JobTDate,ClientId,Comment) VALUES ('backup-frontend-00-int.2017-07-17_15.12.29_09','backup-frontend-00-int','B','I','C','2017-07-17 15:12:29',1500293549,15,'')' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 0 fields bareos-dir (500): postgresql.c:658-0 we have 0 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:863-0 sql_insert_autokey_record executing query 'SELECT currval('Job_Jobid_seq')' bareos-dir (500): postgresql.c:876-0 exec donebareos-dir (500): postgresql.c:879-0 getting valuebareos-dir (500): postgresql.c:881-0 got value '5224' which became 5224 bareos-dir (100): job.c:198-0 Created job record JobId=5224 Name=backup-frontend-00-int.2017-07-17_15.12.29_09 Type=B Level=I bareos-dir (150): dir_plugins.c:520-0 === enter new_plugins === bareos-dir (150): dir_plugins.c:522-0 No dir plugin list! bareos-dir (150): dir_plugins.c:210-0 No bplugin_list: generate_plugin_event ignored. bareos-dir (200): dird_conf.c:1913-0 FILESETRES::print_config bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SELECT FileSetId,CreateTime FROM FileSet WHERE FileSet='FrontEnd' AND MD5='y7/g06oxIG+0oF4U7ioVMC'' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 2 fields bareos-dir (500): postgresql.c:658-0 we have 1 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:758-0 we need space for 16 bytes bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '15' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '1' has value '2017-03-19 03:09:23' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff630005618 bareos-dir (119): job.c:1363-0 Created FileSet FrontEnd record 15 bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SELECT StartTime, Job FROM Job WHERE JobStatus IN ('T','W') AND Type='B' AND Level='F' AND Name='backup-frontend-00-int' AND ClientId=15 AND FileSetId=15 ORDER BY StartTime DESC LIMIT 1' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 2 fields bareos-dir (500): postgresql.c:658-0 we have 1 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:758-0 we need space for 16 bytes bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '2017-07-02 01:09:04' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '1' has value 'backup-frontend-00-int.2017-07-02_01.00.00_56' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff630005618 bareos-dir (500): postgresql.c:622-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-frontend-00-int' AND ClientId=15 AND FileSetId=15 ORDER BY StartTime DESC LIMIT 1' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 2 fields bareos-dir (500): postgresql.c:658-0 we have 1 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:758-0 we need space for 16 bytes bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '2017-07-04 01:04:13' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '1' has value 'backup-frontend-00-int.2017-07-04_01.00.00_39' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff630005618 bareos-dir (100): sql_find.c:129-0 Got start time: 2017-07-04 01:04:13, job: backup-frontend-00-int.2017-07-04_01.00.00_39 bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SELECT StartTime, Job FROM Job WHERE JobStatus IN ('T','W') AND Type='B' AND Level='F' AND Name='backup-frontend-00-int' AND ClientId=15 AND FileSetId=15 ORDER BY StartTime DESC LIMIT 1' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 2 fields bareos-dir (500): postgresql.c:658-0 we have 1 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:758-0 we need space for 16 bytes bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '2017-07-02 01:09:04' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '1' has value 'backup-frontend-00-int.2017-07-02_01.00.00_56' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff630005618 bareos-dir (100): sql_find.c:179-0 Got start time: 2017-07-02 01:09:04 bareos-dir (50): job.c:1072-0 have_full=1 do_full=0 now=1500293549 full_time=1498946944 bareos-dir (500): postgresql.c:622-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 Job='backup-frontend-00-int.2017-07-04_01.00.00_39'' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 23 fields bareos-dir (500): postgresql.c:658-0 we have 1 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:758-0 we need space for 184 bytes bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '36' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '1' has value '1499018226' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '2' has value '2' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '3' has value '2017-07-04 01:04:13' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '4' has value '2017-07-04 01:04:14' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '5' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '6' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '7' has value '1499119454' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '8' has value 'backup-frontend-00-int.2017-07-04_01.00.00_39' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '9' has value 'T' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '10' has value 'B' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '11' has value 'I' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '12' has value '15' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '13' has value 'backup-frontend-00-int' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '14' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '15' has value '2017-07-04 01:04:14' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '16' has value '5213' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '17' has value '15' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '18' has value '2017-07-04 01:00:00' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '19' has value '2017-07-04 01:04:14' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '20' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '21' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '22' has value '0' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff630008958 bareos-dir (100): job.c:1177-0 Level=I last start time=2017-07-04 01:04:13 job=backup-frontend-00-int.2017-07-04_01.00.00_39 bareos-dir (100): job.c:1184-0 entering apply_pool_overrides() bareos-dir (110): job.c:851-0 get_or_create_pool=Incremental bareos-dir (500): postgresql.c:622-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,MinBlocksize,MaxBlocksize FROM Pool WHERE Pool.Name='Incremental'' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 22 fields bareos-dir (500): postgresql.c:658-0 we have 1 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:758-0 we need space for 176 bytes bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '2' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '1' has value 'Incremental' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '2' has value '27' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '3' has value '100' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '4' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '5' has value '1' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '6' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '7' has value '1' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '8' has value '1' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '9' has value '2592000' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '10' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '11' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '12' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '13' has value '26843545600' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '14' has value 'Backup' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '16' has value 'Incremental-' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '18' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '19' has value '1' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '20' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '21' has value '0' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff630008958 bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SELECT count(*) from Media WHERE PoolId=2' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 1 fields bareos-dir (500): postgresql.c:658-0 we have 1 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:758-0 we need space for 8 bytes bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '27' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff6300041a8 bareos-dir (400): sql_get.c:706-0 Actual NumVols=27 Pool NumVols=27 bareos-dir (100): storage.c:145-0 wstorage=bs-00-inc bareos-dir (100): storage.c:154-0 wstore=bs-00-inc where=Pool resource bareos-dir (900): job.c:1730-0 cloned=0 run_cmds=0 bareos-dir (150): dir_plugins.c:210-0 No bplugin_list: generate_plugin_event ignored. bareos-dir (200): job.c:81-0 Add jrc to work queue bareos-dir (100): ua_run.c:520-0 JobId=5224 NewJobId=5224 using pool Incremental priority=10 bareos-dir (800): output_formatter.c:96-0 obj start: run bareos-dir (800): output_formatter.c:344-0 obj: jobid:5224 bareos-dir (800): output_formatter.c:146-0 obj end: run bareos-dir (50): jobq.c:1016-0 Inc Wstore=bs-00-inc wncj=1 bareos-dir (50): jobq.c:904-0 Inc Client=frontend-00-int-fd rncj=1 bareos-dir (50): jobq.c:936-0 Inc Job=backup-frontend-00-int rncj=1 bareos-dir (580): message.c:519-0 Close_msg jcr=7ff630001078 bareos-dir (200): job.c:1498-0 Start dird free_jcr bareos-dir (200): job.c:1562-0 End dird free_jcr bareos-dir (580): message.c:519-0 Close_msg jcr=0 bareos-dir (850): message.c:549-0 ===Begin close msg resource at 703448 bareos-dir (850): message.c:663-0 Done walking message chain. bareos-dir (850): message.c:670-0 ===End close msg resource bareos-dir (110): socket_server.c:86-0 Conn: Hello admin calling bareos-dir (800): jcr.c:1005-0 set_jcr_job_status(*System*, C) bareos-dir (800): jcr.c:1016-0 OnEntry JobStatus= newJobstatus=C bareos-dir (800): jcr.c:1026-0 Set new stat. old: ,0 new: C,0 bareos-dir (800): jcr.c:1031-0 leave setJobStatus old= new=C bareos-dir (100): storage.c:145-0 wstorage=bs-00-inc bareos-dir (100): storage.c:154-0 wstore=bs-00-inc where=Pool resource bareos-dir (100): job.c:1459-0 JobId=0 created Job=-Console-.2017-07-17_15.12.29_10 bareos-dir (800): jcr.c:1005-0 set_jcr_job_status(-Console-.2017-07-17_15.12.29_10, R) bareos-dir (800): jcr.c:1016-0 OnEntry JobStatus=C newJobstatus=R bareos-dir (800): jcr.c:1026-0 Set new stat. old: C,0 new: R,0 bareos-dir (800): jcr.c:1031-0 leave setJobStatus old=C new=R bareos-dir (800): watchdog.c:198-0 Registered watchdog 7ff6240024e8, interval 600 one shot bareos-dir (900): btimers.c:180-0 Start bsock timer 7ff624002478 tid=7ff63bfff700 for 600 secs at 1500293549 bareos-dir (50): cram-md5.c:68-0 send: auth cram-md5 <417340903.1500293549@bareos-dir> ssl=0 bareos-dir (50): cram-md5.c:94-0 Authenticate OK vE1QZbI3uhMCkYaPfe+Q5g bareos-dir (100): cram-md5.c:123-0 cram-get received: auth cram-md5 <4396203231.1500293549@php-bsock> ssl=0 bareos-dir (99): cram-md5.c:143-0 sending resp to challenge: Y5I3i6/sOlppc7YWlx+RqA bareos-dir (900): btimers.c:194-0 Stop bsock timer 7ff624002478 tid=7ff63bfff700 at 1500293549. bareos-dir (800): watchdog.c:218-0 Unregistered watchdog 7ff6240024e8 bareos-dir (900): scan.c:185-0 Next arg=.api 2 compact=yes bareos-dir (900): scan.c:209-0 End arg=.api next=2 compact=yes bareos-dir (900): scan.c:185-0 Next arg=2 compact=yes bareos-dir (900): scan.c:209-0 End arg=2 next=compact=yes bareos-dir (900): scan.c:185-0 Next arg=compact=yes bareos-dir (900): scan.c:209-0 End arg=compact=yes next= bareos-dir (900): scan.c:185-0 Next arg= bareos-dir (900): scan.c:209-0 End arg= next= bareos-dir (900): ua_cmds.c:435-0 Command: .api bareos-dir (800): output_formatter.c:819-0 message length (json): 46 bareos-dir (900): scan.c:185-0 Next arg=llist jobs days=7 bareos-dir (900): scan.c:209-0 End arg=llist next=jobs days=7 bareos-dir (900): scan.c:185-0 Next arg=jobs days=7 bareos-dir (900): scan.c:209-0 End arg=jobs next=days=7 bareos-dir (900): scan.c:185-0 Next arg=days=7 bareos-dir (900): scan.c:209-0 End arg=days=7 next= bareos-dir (900): scan.c:185-0 Next arg= bareos-dir (900): scan.c:209-0 End arg= next= bareos-dir (900): ua_cmds.c:435-0 Command: llist bareos-dir (10): ua_audit.c:141-0 : Console [admin] from [127.0.0.1] cmdline llist jobs days=7 bareos-dir (850): message.c:858-0 Enter dispatch_message type=17 msg=bareos-dir: Console [admin] from [127.0.0.1] cmdline llist jobs days=7 bareos-dir (850): message.c:1097-0 APPEND for following msg: bareos-dir: Console [admin] from [127.0.0.1] cmdline llist jobs days=7 bareos-dir (100): ua_db.c:148-0 UA Open database bareos-dir (100): sql_pooling.c:60-0 db_sql_get_non_pooled_connection allocating 1 new non pooled database connection to database bareos, backend type postgresql bareos-dir (100): cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename dbi, partly_compare = true bareos-dir (100): cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename mysql, partly_compare = false bareos-dir (100): cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename postgresql, partly_compare = false bareos-dir (100): postgresql.c:1229-0 db_init_database first time bareos-dir (50): postgresql.c:239-0 pg_real_connect done bareos-dir (50): postgresql.c:241-0 db_user=bareos db_name=bareos db_password=IsvufcafOrb9 bareos-dir (500): postgresql.c:579-0 db_sql_query starts with 'SELECT VersionId FROM Version' bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SELECT VersionId FROM Version' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 1 fields bareos-dir (500): postgresql.c:658-0 we have 1 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:589-0 db_sql_query succeeded. checking handler bareos-dir (500): postgresql.c:592-0 db_sql_query invoking handler bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:758-0 we need space for 8 bytes bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '2004' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff62400c688 bareos-dir (500): postgresql.c:594-0 db_sql_query sql_fetch_row worked bareos-dir (800): sql.c:77-0 int_handler starts with row pointing at 2400c688 bareos-dir (800): sql.c:80-0 int_handler finds '2004' bareos-dir (800): sql.c:86-0 int_handler finishes bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:786-0 sql_fetch_row row number '1' is NOT acceptable (0..1) bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 0 bareos-dir (500): postgresql.c:601-0 db_sql_query finished bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SET datestyle TO 'ISO, YMD'' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 0 fields bareos-dir (500): postgresql.c:658-0 we have 0 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SET cursor_tuple_fraction=1' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 0 fields bareos-dir (500): postgresql.c:658-0 we have 0 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SET standard_conforming_strings=on' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 0 fields bareos-dir (500): postgresql.c:658-0 we have 0 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SELECT getdatabaseencoding()' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 1 fields bareos-dir (500): postgresql.c:658-0 we have 1 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:758-0 we need space for 8 bytes bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value 'SQL_ASCII' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff62400c688 bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SET client_encoding TO 'SQL_ASCII'' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 0 fields bareos-dir (500): postgresql.c:658-0 we have 0 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (150): ua_db.c:181-0 DB bareos opened bareos-dir (20): ua_output.c:556-0 list: llist jobs days=7 bareos-dir (500): postgresql.c:622-0 sql_query starts with 'SELECT DISTINCT Job.JobId, Job.Job, Job.Name, Job.PurgedFiles, Job.Type, Job.Level, Job.ClientId, Client.Name as Client, Job.JobStatus, Job.SchedTime, Job.StartTime, Job.EndTime, Job.RealEndTime, Job.JobTDate, Job.VolSessionId, Job.VolSessionTime, Job.JobFiles, Job.JobBytes, Job.JobErrors, Job.JobMissingFiles, Job.PoolId, Pool.Name as PoolName, Job.PriorJobId, Job.FileSetId, FileSet.FileSet FROM Job LEFT JOIN Client ON Client.ClientId=Job.ClientId LEFT JOIN Pool ON Pool.PoolId=Job.PoolId LEFT JOIN JobMedia ON JobMedia.JobId=Job.JobId LEFT JOIN Media ON JobMedia.MediaId=Media.MediaId LEFT JOIN FileSet ON FileSet.FileSetId=Job.FileSetId WHERE Job.JobId > 0 AND Job.SchedTime > '2017-07-10 15:12:29' ORDER BY StartTime' bareos-dir (500): postgresql.c:649-0 we have a result bareos-dir (500): postgresql.c:655-0 we have 25 fields bareos-dir (500): postgresql.c:658-0 we have 4 rows bareos-dir (500): postgresql.c:709-0 sql_query finishing bareos-dir (800): output_formatter.c:168-0 array start: jobs bareos-dir (800): output_formatter.c:194-0 result stack: 2 bareos-dir (800): sql.c:718-0 list_result starts bareos-dir (800): sql.c:734-0 list_result starts looking at 25 fields bareos-dir (800): sql.c:740-0 list_result processing field 0 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:906-0 allocating space for 25 fields bareos-dir (500): postgresql.c:911-0 filling field 0 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'jobid' has length='4' type='23' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 1 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'job' has length='45' type='25' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 2 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'name' has length='22' type='25' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 3 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'purgedfiles' has length='1' type='21' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 4 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'type' has length='1' type='1042' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 5 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'level' has length='1' type='1042' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 6 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'clientid' has length='2' type='23' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 7 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'client' has length='18' type='25' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 8 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'jobstatus' has length='1' type='1042' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 9 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'schedtime' has length='19' type='1114' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 10 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'starttime' has length='19' type='1114' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 11 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'endtime' has length='19' type='1114' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 12 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'realendtime' has length='4' type='1114' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 13 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'jobtdate' has length='10' type='20' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 14 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'volsessionid' has length='1' type='23' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 15 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'volsessiontime' has length='1' type='23' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 16 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'jobfiles' has length='1' type='23' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 17 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'jobbytes' has length='1' type='20' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 18 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'joberrors' has length='1' type='23' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 19 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'jobmissingfiles' has length='1' type='23' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 20 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'poolid' has length='1' type='23' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 21 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'poolname' has length='11' type='25' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 22 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'priorjobid' has length='1' type='23' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 23 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'filesetid' has length='2' type='23' and IsNull=0 bareos-dir (500): postgresql.c:911-0 filling field 24 bareos-dir (500): postgresql.c:934-0 sql_fetch_field finds field 'fileset' has length='8' type='25' and IsNull=0 bareos-dir (800): sql.c:740-0 list_result processing field 1 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 2 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 3 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 4 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 5 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 6 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 7 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 8 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 9 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 10 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 11 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 12 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 13 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 14 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 15 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 16 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 17 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 18 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 19 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 20 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 21 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 22 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 23 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:740-0 list_result processing field 24 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): sql.c:776-0 list_result finished first loop bareos-dir (800): sql.c:901-0 list_result starts vertical list at 25 fields bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:758-0 we need space for 200 bytes bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '0' is acceptable (0..4) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '5221' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '1' has value 'backup-frontend-00-int.2017-07-17_15.00.11_21' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '2' has value 'backup-frontend-00-int' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '3' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '4' has value 'B' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '5' has value 'I' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '6' has value '15' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '7' has value 'frontend-00-int-fd' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '8' has value 'f' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '9' has value '2017-07-17 15:00:11' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '10' has value '2017-07-17 15:00:13' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '11' has value '2017-07-17 15:00:13' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '12' has value '' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '13' has value '1500292813' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '14' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '16' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '18' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '19' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '20' has value '2' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '21' has value 'Incremental' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '22' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '23' has value '15' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '24' has value 'FrontEnd' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff62400ddf8 bareos-dir (800): output_formatter.c:96-0 obj start: bareos-dir (800): output_formatter.c:136-0 result stack: 3 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): output_formatter.c:146-0 obj end: bareos-dir (800): output_formatter.c:151-0 result stack: 2 bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '1' is acceptable (0..4) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '5222' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '1' has value 'backup-frontend-00-int.2017-07-17_15.05.51_04' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '2' has value 'backup-frontend-00-int' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '3' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '4' has value 'B' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '5' has value 'I' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '6' has value '15' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '7' has value 'frontend-00-int-fd' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '8' has value 'f' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '9' has value '2017-07-17 15:05:51' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '10' has value '2017-07-17 15:05:53' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '11' has value '2017-07-17 15:05:53' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '12' has value '' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '13' has value '1500293153' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '14' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '16' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '18' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '19' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '20' has value '2' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '21' has value 'Incremental' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '22' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '23' has value '15' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '24' has value 'FrontEnd' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff62400ddf8 bareos-dir (800): output_formatter.c:96-0 obj start: bareos-dir (800): output_formatter.c:136-0 result stack: 3 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): output_formatter.c:146-0 obj end: bareos-dir (800): output_formatter.c:151-0 result stack: 2 bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '2' is acceptable (0..4) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '5223' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '1' has value 'backup-frontend-00-int.2017-07-17_15.10.00_10' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '2' has value 'backup-frontend-00-int' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '3' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '4' has value 'B' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '5' has value 'I' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '6' has value '15' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '7' has value 'frontend-00-int-fd' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '8' has value 'f' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '9' has value '2017-07-17 15:10:00' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '10' has value '2017-07-17 15:10:02' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '11' has value '2017-07-17 15:10:02' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '12' has value '' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '13' has value '1500293402' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '14' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '16' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '18' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '19' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '20' has value '2' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '21' has value 'Incremental' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '22' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '23' has value '15' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '24' has value 'FrontEnd' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff62400ddf8 bareos-dir (800): output_formatter.c:96-0 obj start: bareos-dir (800): output_formatter.c:136-0 result stack: 3 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): output_formatter.c:146-0 obj end: bareos-dir (800): output_formatter.c:151-0 result stack: 2 bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:772-0 sql_fetch_row row number '3' is acceptable (0..4) bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '0' has value '5224' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '1' has value 'backup-frontend-00-int.2017-07-17_15.12.29_09' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '2' has value 'backup-frontend-00-int' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '3' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '4' has value 'B' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '5' has value 'I' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '6' has value '15' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '7' has value 'frontend-00-int-fd' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '8' has value 'C' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '9' has value '2017-07-17 15:12:29' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '10' has value '' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '11' has value '' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '12' has value '' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '13' has value '1500293549' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '14' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '16' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '18' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '19' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '20' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '21' has value '' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '22' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '23' has value '0' bareos-dir (500): postgresql.c:778-0 sql_fetch_row field '24' has value '' bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 7ff62400ddf8 bareos-dir (800): output_formatter.c:96-0 obj start: bareos-dir (800): output_formatter.c:136-0 result stack: 3 bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (500): postgresql.c:899-0 sql_fetch_field starts bareos-dir (800): output_formatter.c:146-0 obj end: bareos-dir (800): output_formatter.c:151-0 result stack: 2 bareos-dir (500): postgresql.c:746-0 sql_fetch_row start bareos-dir (500): postgresql.c:786-0 sql_fetch_row row number '4' is NOT acceptable (0..4) bareos-dir (500): postgresql.c:789-0 sql_fetch_row finishes returning 0 bareos-dir (800): output_formatter.c:204-0 array end: jobs bareos-dir (800): output_formatter.c:209-0 result stack: 1 bareos-dir (800): output_formatter.c:819-0 message length (json): 2181 bareos-dir (580): message.c:519-0 Close_msg jcr=7ff624001078 bareos-dir (200): job.c:1498-0 Start dird free_jcr bareos-dir (200): job.c:1562-0 End dird free_jcr bareos-dir (580): message.c:519-0 Close_msg jcr=0 bareos-dir (850): message.c:549-0 ===Begin close msg resource at 703448 bareos-dir (850): message.c:663-0 Done walking message chain. bareos-dir (850): message.c:670-0 ===End close msg resource bareos-dir (200): job.c:417-5224 =====Start Job========= bareos-dir (800): jcr.c:1005-5224 set_jcr_job_status(backup-frontend-00-int.2017-07-17_15.12.29_09, R) bareos-dir (800): jcr.c:1016-5224 OnEntry JobStatus=C newJobstatus=R bareos-dir (800): jcr.c:1026-5224 Set new stat. old: C,0 new: R,0 bareos-dir (800): jcr.c:1031-5224 leave setJobStatus old=C new=R bareos-dir (200): job.c:442-5224 Warning, job->RunScripts is empty bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'UPDATE Job SET JobStatus='R',Level='I',StartTime='2017-07-17 15:12:31',ClientId=15,JobTDate=1500293551,PoolId=2,FileSetId=15 WHERE JobId=5224' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (200): runscript.c:149-5224 runscript: running all RUNSCRIPT object (BeforeJob) JobStatus=R bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'UPDATE Job SET JobStatus='R',Level='I',StartTime='2017-07-17 15:12:31',ClientId=15,JobTDate=1500293551,PoolId=2,FileSetId=15 WHERE JobId=5224' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (150): dir_plugins.c:210-5224 No bplugin_list: generate_plugin_event ignored. bareos-dir (850): message.c:1557-5224 Enter Jmsg type=6 bareos-dir (850): message.c:858-5224 Enter dispatch_message type=6 msg=bareos-dir JobId 5224: Start Backup JobId 5224, Job=backup-frontend-00-int.2017-07-17_15.12.29_09 bareos-dir (850): message.c:1069-5224 MAIL for following msg: bareos-dir JobId 5224: Start Backup JobId 5224, Job=backup-frontend-00-int.2017-07-17_15.12.29_09 bareos-dir (850): message.c:475-5224 mailname=/var/lib/bareos/bareos-dir.backup-frontend-00-int.2017-07-17_15.12.29_09.805368792.mail bareos-dir (850): message.c:975-5224 CONSOLE for following msg: bareos-dir JobId 5224: Start Backup JobId 5224, Job=backup-frontend-00-int.2017-07-17_15.12.29_09 bareos-dir (850): message.c:1097-5224 APPEND for following msg: bareos-dir JobId 5224: Start Backup JobId 5224, Job=backup-frontend-00-int.2017-07-17_15.12.29_09 bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (5224,'2017-07-17 15:12:31','bareos-dir JobId 5224: Start Backup JobId 5224, Job=backup-frontend-00-int.2017-07-17_15.12.29_09 ')' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (800): jcr.c:1005-5224 set_jcr_job_status(backup-frontend-00-int.2017-07-17_15.12.29_09, R) bareos-dir (800): jcr.c:1016-5224 OnEntry JobStatus=R newJobstatus=R bareos-dir (800): jcr.c:1026-5224 Set new stat. old: R,0 new: R,0 bareos-dir (100): backup.c:397-5224 JobId=5224 JobLevel=I bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'UPDATE Job SET JobStatus='R',Level='I',StartTime='2017-07-17 15:12:31',ClientId=15,JobTDate=1500293551,PoolId=2,FileSetId=15 WHERE JobId=5224' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (110): backup.c:419-5224 Open connection with storage daemon bareos-dir (800): jcr.c:1005-5224 set_jcr_job_status(backup-frontend-00-int.2017-07-17_15.12.29_09, S) bareos-dir (800): jcr.c:1016-5224 OnEntry JobStatus=R newJobstatus=S bareos-dir (800): jcr.c:1026-5224 Set new stat. old: R,0 new: S,0 bareos-dir (800): jcr.c:1031-5224 leave setJobStatus old=R new=S bareos-dir (100): sd_cmds.c:114-5224 bnet_connect to Storage daemon bs-00.int.metahouse.ru:9103 bareos-dir (100): bsock.c:70-5224 All source addresses bareos-dir (800): watchdog.c:198-5224 Registered watchdog 7ff62800b758, interval 1800 one shot bareos-dir (900): btimers.c:148-5224 Start thread timer 7ff62800d898 tid 7ff640f26700 for 1800 secs. bareos-dir (100): bsock_tcp.c:230-5224 Current host[ipv4;192.168.10.12;9103] All host[ipv4;192.168.10.12;9103] bareos-dir (100): bsock_tcp.c:153-5224 who=Storage daemon host=bs-00.int.metahouse.ru port=9103 bareos-dir (900): btimers.c:208-5224 Stop thread timer 7ff62800d898 tid=7ff640f26700. bareos-dir (800): watchdog.c:218-5224 Unregistered watchdog 7ff62800b758 bareos-dir (800): watchdog.c:198-5224 Registered watchdog 7ff62800b758, interval 600 one shot bareos-dir (900): btimers.c:180-5224 Start bsock timer 7ff62800d898 tid=7ff640f26700 for 600 secs at 1500293551 bareos-dir (100): cram-md5.c:123-5224 cram-get received: auth cram-md5 <1038093631.1500293551@bs-00> ssl=0 bareos-dir (99): cram-md5.c:143-5224 sending resp to challenge: H9oKyB1Yqg4ZEEcm18+M4C bareos-dir (50): cram-md5.c:75-5224 send: auth cram-md5 <1102297364.1500293551@bareos-dir> ssl=0 bareos-dir (50): cram-md5.c:94-5224 Authenticate OK g+5v5/E66VpxMlYvN0/kID bareos-dir (900): btimers.c:194-5224 Stop bsock timer 7ff62800d898 tid=7ff640f26700 at 1500293551. bareos-dir (800): watchdog.c:218-5224 Unregistered watchdog 7ff62800b758 bareos-dir (116): authenticate.c:97-5224 >stored: 1000 OK auth bareos-dir (110): authenticate.c:104-5224 stored: JobId=5224 job=backup-frontend-00-int.2017-07-17_15.12.29_09 job_name=backup-frontend-00-int client_name=frontend-00-int-fd type=66 level=73 FileSet=FrontEnd NoAttr=0 SpoolAttr=0 FileSetMD5=y7/g06oxIG+0oF4U7ioVMC SpoolData=0 PreferMountedVols=1 SpoolSize=0 rerunning=0 VolSessionId=0 VolSessionTime=0 Quota=0 Protocol=0 BackupFormat=Native bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 92: 3000 OK Job SDid=46 SDtime=1499018226 Authorization=JPMB-LMPO-JNHO-MJEK-LJBB-GKGA-LNKG-JCFB bareos-dir (100): msgchan.c:225-5224 stored: use storage=bs-00-inc media_type=File pool_name=Incremental pool_type=Backup append=1 copy=0 stripe=0 bareos-dir (100): msgchan.c:330-5224 >stored: use device=IncFileStorage bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 125: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 FindMedia=1 pool_name=Incremental media_type=File unwanted_volumes= bareos-dir (900): getmsg.c:254-5224 Catalog req jcr 0x30006f88: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 FindMedia=1 pool_name=Incremental media_type=File unwanted_volumes= bareos-dir (100): catreq.c:124-5224 catreq CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 FindMedia=1 pool_name=Incremental media_type=File unwanted_volumes= bareos-dir (500): postgresql.c:622-5224 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,MinBlocksize,MaxBlocksize FROM Pool WHERE Pool.Name='Incremental'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 22 fields bareos-dir (500): postgresql.c:658-5224 we have 1 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (500): postgresql.c:746-5224 sql_fetch_row start bareos-dir (500): postgresql.c:758-5224 we need space for 176 bytes bareos-dir (500): postgresql.c:772-5224 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '0' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '1' has value 'Incremental' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '2' has value '27' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '3' has value '100' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '4' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '5' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '6' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '7' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '8' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '9' has value '2592000' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '10' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '11' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '12' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '13' has value '26843545600' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '14' has value 'Backup' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '16' has value 'Incremental-' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '18' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '19' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '20' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '21' has value '0' bareos-dir (500): postgresql.c:789-5224 sql_fetch_row finishes returning 7ff62800d088 bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'SELECT count(*) from Media WHERE PoolId=2' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 1 fields bareos-dir (500): postgresql.c:658-5224 we have 1 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (500): postgresql.c:746-5224 sql_fetch_row start bareos-dir (500): postgresql.c:758-5224 we need space for 8 bytes bareos-dir (500): postgresql.c:772-5224 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '0' has value '27' bareos-dir (500): postgresql.c:789-5224 sql_fetch_row finishes returning 7ff628002688 bareos-dir (400): sql_get.c:706-5224 Actual NumVols=27 Pool NumVols=27 bareos-dir (50): next_vol.c:69-5224 find_next_vol_for_append: JobId=5224 PoolId=2, MediaType=File bareos-dir (100): sql_find.c:415-5224 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=2 AND MediaType='File' AND Enabled=1 AND VolStatus='Append' ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 1 bareos-dir (500): postgresql.c:622-5224 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,MinBlocksize,MaxBlocksize FROM Media WHERE PoolId=2 AND MediaType='File' AND Enabled=1 AND VolStatus='Append' ORDER BY LastWritten IS NULL,LastWritten DESC,MediaId LIMIT 1' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 40 fields bareos-dir (500): postgresql.c:658-5224 we have 1 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (500): postgresql.c:746-5224 sql_fetch_row start bareos-dir (500): postgresql.c:758-5224 we need space for 320 bytes bareos-dir (500): postgresql.c:772-5224 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '0' has value '12' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '1' has value 'Incremental-0012' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '2' has value '14' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '3' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '4' has value '191267' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '5' has value '12338406747' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '6' has value '92' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '7' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '8' has value '1439631' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '9' has value '26843545600' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '10' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '11' has value 'File' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '12' has value 'Append' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '13' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '14' has value '2592000' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '16' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '18' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '19' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '20' has value '2017-07-04 01:03:50' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '21' has value '2017-07-04 01:14:45' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '22' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '23' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '24' has value '3748455028' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '25' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '26' has value '2017-07-04 01:03:29' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '27' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '28' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '29' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '30' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '31' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '32' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '33' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '34' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '35' has value '39231914' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '36' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '37' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '38' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '39' has value '0' bareos-dir (500): postgresql.c:789-5224 sql_fetch_row finishes returning 7ff62800d1b8 bareos-dir (40): sql_find.c:513-5224 Rtn numrows=1 bareos-dir (50): next_vol.c:188-5224 VolJobs=14 FirstWritten=1499119430 bareos-dir (50): next_vol.c:283-5224 Vol=Incremental-0012 expired=0 bareos-dir (50): next_vol.c:206-5224 return ok=1 find_next_vol bareos-dir (40): catreq.c:149-5224 find_media ok=1 idx=1 vol=Incremental-0012 bareos-dir (100): catreq.c:97-5224 Vol Info for backup-frontend-00-int.2017-07-17_15.12.29_09: 1000 OK VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338406747 VolMounts=92 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:368-5224 >CatReq response: 1000 OK VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338406747 VolMounts=92 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:369-5224 Leave catreq jcr 0x30006f88 bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 41: 3000 OK use device device=IncFileStorage bareos-dir (100): msgchan.c:336-5224 ssl=0 bareos-dir (99): cram-md5.c:143-5224 sending resp to challenge: L/wS2i+wVS/kAUQ8GT+KqB bareos-dir (50): cram-md5.c:75-5224 send: auth cram-md5 <1177181654.1500293551@bareos-dir> ssl=0 bareos-dir (50): cram-md5.c:94-5224 Authenticate OK C7ARjDlrNU/Ue/0xy5+2GD bareos-dir (900): btimers.c:194-5224 Stop bsock timer 7ff62800ce08 tid=7ff640f26700 at 1500293551. bareos-dir (800): watchdog.c:218-5224 Unregistered watchdog 7ff62800d398 bareos-dir (116): authenticate.c:161-5224 >filed: 1000 OK auth bareos-dir (110): authenticate.c:170-5224 filed: JobId=5224 Job=backup-frontend-00-int.2017-07-17_15.12.29_09 SDid=46 SDtime=1499018226 Authorization=JPMB-LMPO-JNHO-MJEK-LJBB-GKGA-LNKG-JCFB bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 103: 2000 OK Job 16.2.4 (01Jul16) x86_64-pc-linux-gnu,debian,Debian GNU/Linux 8.0 (jessie),Debian_8.0,x86_64 bareos-dir (110): fd_cmds.c:216-5224 filed: bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 14: 2000 OK level bareos-dir (500): fd_cmds.c:614-5224 Inc/Exc name=F /etc/nginx bareos-dir (500): fd_cmds.c:614-5224 Inc/Exc name=F /var/www bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 16: 2000 OK include bareos-dir (500): postgresql.c:579-5224 db_sql_query starts with 'SELECT JobId,ObjectLength,ObjectFullLength,ObjectIndex,ObjectType,ObjectCompression,FileIndex,ObjectName,RestoreObject,PluginName FROM RestoreObject WHERE JobId IN (5213) AND ObjectType = 25 ORDER BY ObjectIndex ASC' bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'SELECT JobId,ObjectLength,ObjectFullLength,ObjectIndex,ObjectType,ObjectCompression,FileIndex,ObjectName,RestoreObject,PluginName FROM RestoreObject WHERE JobId IN (5213) AND ObjectType = 25 ORDER BY ObjectIndex ASC' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 10 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (500): postgresql.c:589-5224 db_sql_query succeeded. checking handler bareos-dir (500): postgresql.c:592-5224 db_sql_query invoking handler bareos-dir (500): postgresql.c:746-5224 sql_fetch_row start bareos-dir (500): postgresql.c:758-5224 we need space for 80 bytes bareos-dir (500): postgresql.c:786-5224 sql_fetch_row row number '0' is NOT acceptable (0..0) bareos-dir (500): postgresql.c:789-5224 sql_fetch_row finishes returning 0 bareos-dir (500): postgresql.c:601-5224 db_sql_query finished bareos-dir (500): postgresql.c:579-5224 db_sql_query starts with 'SELECT JobId,ObjectLength,ObjectFullLength,ObjectIndex,ObjectType,ObjectCompression,FileIndex,ObjectName,RestoreObject,PluginName FROM RestoreObject WHERE JobId IN (5213) AND ObjectType = 27 ORDER BY ObjectIndex ASC' bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'SELECT JobId,ObjectLength,ObjectFullLength,ObjectIndex,ObjectType,ObjectCompression,FileIndex,ObjectName,RestoreObject,PluginName FROM RestoreObject WHERE JobId IN (5213) AND ObjectType = 27 ORDER BY ObjectIndex ASC' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 10 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (500): postgresql.c:589-5224 db_sql_query succeeded. checking handler bareos-dir (500): postgresql.c:592-5224 db_sql_query invoking handler bareos-dir (500): postgresql.c:746-5224 sql_fetch_row start bareos-dir (500): postgresql.c:758-5224 we need space for 80 bytes bareos-dir (500): postgresql.c:786-5224 sql_fetch_row row number '0' is NOT acceptable (0..0) bareos-dir (500): postgresql.c:789-5224 sql_fetch_row finishes returning 0 bareos-dir (500): postgresql.c:601-5224 db_sql_query finished bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 32: 2000 OK FDSecureEraseCmd *None* bareos-dir (400): fd_cmds.c:294-5224 Got FD Secure Erase Cmd: *None* bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 16: 2000 OK storage bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'UPDATE Job SET JobStatus='R',Level='I',StartTime='2017-07-17 15:12:31',ClientId=15,JobTDate=1500293551,PoolId=2,FileSetId=15 WHERE JobId=5224' bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 61: 3010 Job backup-frontend-00-int.2017-07-17_15.12.29_09 start bareos-dir (400): msgchan.c:435-5224 filed: backup FileIndex=0 bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 15: 2000 OK backup bareos-dir (800): jcr.c:1005-5224 set_jcr_job_status(backup-frontend-00-int.2017-07-17_15.12.29_09, R) bareos-dir (800): jcr.c:1016-5224 OnEntry JobStatus=R newJobstatus=R bareos-dir (800): jcr.c:1026-5224 Set new stat. old: R,0 new: R,0 bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 101: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 GetVolInfo VolName=Incremental-0012 write=1 bareos-dir (900): getmsg.c:254-5224 Catalog req jcr 0x30006f88: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 GetVolInfo VolName=Incremental-0012 write=1 bareos-dir (100): catreq.c:124-5224 catreq CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 GetVolInfo VolName=Incremental-0012 write=1 bareos-dir (100): catreq.c:165-5224 CatReq GetVolInfo Vol=Incremental-0012 bareos-dir (500): postgresql.c:622-5224 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,MinBlocksize,MaxBlocksize FROM Media WHERE VolumeName='Incremental-0012'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 40 fields bareos-dir (500): postgresql.c:658-5224 we have 1 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (500): postgresql.c:746-5224 sql_fetch_row start bareos-dir (500): postgresql.c:758-5224 we need space for 320 bytes bareos-dir (500): postgresql.c:772-5224 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '0' has value '12' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '1' has value 'Incremental-0012' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '2' has value '14' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '3' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '4' has value '191267' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '5' has value '12338406747' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '6' has value '92' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '7' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '8' has value '1439631' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '9' has value '26843545600' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '10' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '11' has value 'File' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '12' has value 'Append' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '13' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '14' has value '2592000' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '16' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '18' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '19' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '20' has value '2017-07-04 01:03:50' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '21' has value '2017-07-04 01:14:45' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '22' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '23' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '24' has value '3748455028' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '25' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '26' has value '2017-07-04 01:03:29' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '27' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '28' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '29' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '30' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '31' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '32' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '33' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '34' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '35' has value '39231914' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '36' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '37' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '38' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '39' has value '0' bareos-dir (500): postgresql.c:789-5224 sql_fetch_row finishes returning 7ff62400efa8 bareos-dir (50): next_vol.c:283-5224 Vol=Incremental-0012 expired=0 bareos-dir (100): catreq.c:97-5224 Vol Info for backup-frontend-00-int.2017-07-17_15.12.29_09: 1000 OK VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338406747 VolMounts=92 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:368-5224 >CatReq response: 1000 OK VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338406747 VolMounts=92 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:369-5224 Leave catreq jcr 0x30006f88 bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 101: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 GetVolInfo VolName=Incremental-0012 write=1 bareos-dir (900): getmsg.c:254-5224 Catalog req jcr 0x30006f88: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 GetVolInfo VolName=Incremental-0012 write=1 bareos-dir (100): catreq.c:124-5224 catreq CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 GetVolInfo VolName=Incremental-0012 write=1 bareos-dir (100): catreq.c:165-5224 CatReq GetVolInfo Vol=Incremental-0012 bareos-dir (500): postgresql.c:622-5224 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,MinBlocksize,MaxBlocksize FROM Media WHERE VolumeName='Incremental-0012'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 40 fields bareos-dir (500): postgresql.c:658-5224 we have 1 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (500): postgresql.c:746-5224 sql_fetch_row start bareos-dir (500): postgresql.c:758-5224 we need space for 320 bytes bareos-dir (500): postgresql.c:772-5224 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '0' has value '12' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '1' has value 'Incremental-0012' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '2' has value '14' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '3' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '4' has value '191267' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '5' has value '12338406747' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '6' has value '92' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '7' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '8' has value '1439631' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '9' has value '26843545600' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '10' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '11' has value 'File' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '12' has value 'Append' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '13' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '14' has value '2592000' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '16' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '18' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '19' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '20' has value '2017-07-04 01:03:50' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '21' has value '2017-07-04 01:14:45' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '22' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '23' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '24' has value '3748455028' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '25' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '26' has value '2017-07-04 01:03:29' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '27' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '28' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '29' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '30' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '31' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '32' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '33' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '34' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '35' has value '39231914' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '36' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '37' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '38' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '39' has value '0' bareos-dir (500): postgresql.c:789-5224 sql_fetch_row finishes returning 7ff62400f658 bareos-dir (50): next_vol.c:283-5224 Vol=Incremental-0012 expired=0 bareos-dir (100): catreq.c:97-5224 Vol Info for backup-frontend-00-int.2017-07-17_15.12.29_09: 1000 OK VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338406747 VolMounts=92 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:368-5224 >CatReq response: 1000 OK VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338406747 VolMounts=92 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:369-5224 Leave catreq jcr 0x30006f88 bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 166: Jmsg Job=backup-frontend-00-int.2017-07-17_15.12.29_09 type=6 level=1500293551 bs-00 JobId 5224: Volume "Incremental-0012" previously written, moving to end of data. bareos-dir (900): getmsg.c:237-5224 Got msg: Jmsg Job=backup-frontend-00-int.2017-07-17_15.12.29_09 type=6 level=1500293551 bs-00 JobId 5224: Volume "Incremental-0012" previously written, moving to end of data. bareos-dir (900): getmsg.c:245-5224 Dispatch msg: bs-00 JobId 5224: Volume "Incremental-0012" previously written, moving to end of data. bareos-dir (850): message.c:858-5224 Enter dispatch_message type=6 msg=bs-00 JobId 5224: Volume "Incremental-0012" previously written, moving to end of data. bareos-dir (850): message.c:1069-5224 MAIL for following msg: bs-00 JobId 5224: Volume "Incremental-0012" previously written, moving to end of data. bareos-dir (850): message.c:975-5224 CONSOLE for following msg: bs-00 JobId 5224: Volume "Incremental-0012" previously written, moving to end of data. bareos-dir (850): message.c:1097-5224 APPEND for following msg: bs-00 JobId 5224: Volume "Incremental-0012" previously written, moving to end of data. bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (5224,'2017-07-17 15:12:31','bs-00 JobId 5224: Volume "Incremental-0012" previously written, moving to end of data. ')' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 219: Jmsg Job=backup-frontend-00-int.2017-07-17_15.12.29_09 type=5 level=1500293551 bs-00 JobId 5224: Warning: For Volume "Incremental-0012": The sizes do not match! Volume=12338415310 Catalog=12338406747 Correcting Catalog bareos-dir (900): getmsg.c:237-5224 Got msg: Jmsg Job=backup-frontend-00-int.2017-07-17_15.12.29_09 type=5 level=1500293551 bs-00 JobId 5224: Warning: For Volume "Incremental-0012": The sizes do not match! Volume=12338415310 Catalog=12338406747 Correcting Catalog bareos-dir (900): getmsg.c:245-5224 Dispatch msg: bs-00 JobId 5224: Warning: For Volume "Incremental-0012": The sizes do not match! Volume=12338415310 Catalog=12338406747 Correcting Catalog bareos-dir (850): message.c:858-5224 Enter dispatch_message type=5 msg=bs-00 JobId 5224: Warning: For Volume "Incremental-0012": The sizes do not match! Volume=12338415310 Catalog=12338406747 Correcting Catalog bareos-dir (850): message.c:1069-5224 MAIL for following msg: bs-00 JobId 5224: Warning: For Volume "Incremental-0012": The sizes do not match! Volume=12338415310 Catalog=12338406747 Correcting Catalog bareos-dir (850): message.c:975-5224 CONSOLE for following msg: bs-00 JobId 5224: Warning: For Volume "Incremental-0012": The sizes do not match! Volume=12338415310 Catalog=12338406747 Correcting Catalog bareos-dir (850): message.c:1097-5224 APPEND for following msg: bs-00 JobId 5224: Warning: For Volume "Incremental-0012": The sizes do not match! Volume=12338415310 Catalog=12338406747 Correcting Catalog bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (5224,'2017-07-17 15:12:31','bs-00 JobId 5224: Warning: For Volume "Incremental-0012": The sizes do not match! Volume=12338415310 Catalog=12338406747 Correcting Catalog ')' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 340: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 UpdateMedia VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=92 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 EndTime=1500293551 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 VolFirstWritten=0 bareos-dir (900): getmsg.c:254-5224 Catalog req jcr 0x30006f88: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 UpdateMedia VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=92 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 EndTime=1500293551 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 VolFirstWritten=0 bareos-dir (100): catreq.c:124-5224 catreq CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 UpdateMedia VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=92 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 EndTime=1500293551 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 VolFirstWritten=0 bareos-dir (400): catreq.c:227-5224 Update media Incremental-0012 oldStat= newStat=Append bareos-dir (500): postgresql.c:622-5224 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,MinBlocksize,MaxBlocksize FROM Media WHERE VolumeName='Incremental-0012'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 40 fields bareos-dir (500): postgresql.c:658-5224 we have 1 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (500): postgresql.c:746-5224 sql_fetch_row start bareos-dir (500): postgresql.c:758-5224 we need space for 320 bytes bareos-dir (500): postgresql.c:772-5224 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '0' has value '12' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '1' has value 'Incremental-0012' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '2' has value '14' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '3' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '4' has value '191267' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '5' has value '12338406747' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '6' has value '92' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '7' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '8' has value '1439631' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '9' has value '26843545600' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '10' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '11' has value 'File' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '12' has value 'Append' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '13' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '14' has value '2592000' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '16' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '18' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '19' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '20' has value '2017-07-04 01:03:50' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '21' has value '2017-07-04 01:14:45' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '22' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '23' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '24' has value '3748455028' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '25' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '26' has value '2017-07-04 01:03:29' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '27' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '28' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '29' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '30' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '31' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '32' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '33' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '34' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '35' has value '39231914' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '36' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '37' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '38' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '39' has value '0' bareos-dir (500): postgresql.c:789-5224 sql_fetch_row finishes returning 7ff62400f658 bareos-dir (400): catreq.c:273-5224 Update media: BefVolJobs=14 After=14 bareos-dir (400): catreq.c:317-5224 db_update_media_record. Stat=Append Vol=Incremental-0012 bareos-dir (100): sql_update.c:322-5224 update_media: FirstWritten=1499119430 bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'UPDATE Media Set LastWritten='2017-07-04 01:14:45' WHERE VolumeName='Incremental-0012'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (400): sql_update.c:385-5224 UPDATE Media SET VolJobs=14,VolFiles=2,VolBlocks=191267,VolBytes=12338415310,VolMounts=92,VolErrors=0,VolWrites=1439631,MaxVolBytes=26843545600,VolStatus='Append',Slot=0,InChanger=0,VolReadTime=0,VolWriteTime=39231914,LabelType=0,StorageId=3,PoolId=2,VolRetention=2592000,VolUseDuration=0,MaxVolJobs=0,MaxVolFiles=0,Enabled=1,LocationId=0,ScratchPoolId=0,RecyclePoolId=0,RecycleCount=3,Recycle=1,ActionOnPurge=1,MinBlocksize=0,MaxBlocksize=0 WHERE VolumeName='Incremental-0012' bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'UPDATE Media SET VolJobs=14,VolFiles=2,VolBlocks=191267,VolBytes=12338415310,VolMounts=92,VolErrors=0,VolWrites=1439631,MaxVolBytes=26843545600,VolStatus='Append',Slot=0,InChanger=0,VolReadTime=0,VolWriteTime=39231914,LabelType=0,StorageId=3,PoolId=2,VolRetention=2592000,VolUseDuration=0,MaxVolJobs=0,MaxVolFiles=0,Enabled=1,LocationId=0,ScratchPoolId=0,RecyclePoolId=0,RecycleCount=3,Recycle=1,ActionOnPurge=1,MinBlocksize=0,MaxBlocksize=0 WHERE VolumeName='Incremental-0012'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (50): next_vol.c:283-5224 Vol=Incremental-0012 expired=0 bareos-dir (100): catreq.c:97-5224 Vol Info for backup-frontend-00-int.2017-07-17_15.12.29_09: 1000 OK VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=92 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:336-5224 >CatReq response: 1000 OK VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=92 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:337-5224 Leave catreq jcr 0x30006f88 bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 340: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 UpdateMedia VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=93 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 EndTime=1500293551 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 VolFirstWritten=0 bareos-dir (900): getmsg.c:254-5224 Catalog req jcr 0x30006f88: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 UpdateMedia VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=93 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 EndTime=1500293551 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 VolFirstWritten=0 bareos-dir (100): catreq.c:124-5224 catreq CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 UpdateMedia VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=93 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 EndTime=1500293551 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 VolFirstWritten=0 bareos-dir (400): catreq.c:227-5224 Update media Incremental-0012 oldStat= newStat=Append bareos-dir (500): postgresql.c:622-5224 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,MinBlocksize,MaxBlocksize FROM Media WHERE VolumeName='Incremental-0012'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 40 fields bareos-dir (500): postgresql.c:658-5224 we have 1 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (500): postgresql.c:746-5224 sql_fetch_row start bareos-dir (500): postgresql.c:758-5224 we need space for 320 bytes bareos-dir (500): postgresql.c:772-5224 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '0' has value '12' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '1' has value 'Incremental-0012' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '2' has value '14' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '3' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '4' has value '191267' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '5' has value '12338415310' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '6' has value '92' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '7' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '8' has value '1439631' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '9' has value '26843545600' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '10' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '11' has value 'File' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '12' has value 'Append' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '13' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '14' has value '2592000' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '16' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '18' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '19' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '20' has value '2017-07-04 01:03:50' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '21' has value '2017-07-04 01:14:45' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '22' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '23' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '24' has value '3748455028' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '25' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '26' has value '2017-07-04 01:03:29' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '27' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '28' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '29' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '30' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '31' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '32' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '33' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '34' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '35' has value '39231914' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '36' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '37' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '38' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '39' has value '0' bareos-dir (500): postgresql.c:789-5224 sql_fetch_row finishes returning 7ff62400f658 bareos-dir (400): catreq.c:273-5224 Update media: BefVolJobs=14 After=14 bareos-dir (400): catreq.c:317-5224 db_update_media_record. Stat=Append Vol=Incremental-0012 bareos-dir (100): sql_update.c:322-5224 update_media: FirstWritten=1499119430 bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'UPDATE Media Set LastWritten='2017-07-04 01:14:45' WHERE VolumeName='Incremental-0012'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (400): sql_update.c:385-5224 UPDATE Media SET VolJobs=14,VolFiles=2,VolBlocks=191267,VolBytes=12338415310,VolMounts=93,VolErrors=0,VolWrites=1439631,MaxVolBytes=26843545600,VolStatus='Append',Slot=0,InChanger=0,VolReadTime=0,VolWriteTime=39231914,LabelType=0,StorageId=3,PoolId=2,VolRetention=2592000,VolUseDuration=0,MaxVolJobs=0,MaxVolFiles=0,Enabled=1,LocationId=0,ScratchPoolId=0,RecyclePoolId=0,RecycleCount=3,Recycle=1,ActionOnPurge=1,MinBlocksize=0,MaxBlocksize=0 WHERE VolumeName='Incremental-0012' bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'UPDATE Media SET VolJobs=14,VolFiles=2,VolBlocks=191267,VolBytes=12338415310,VolMounts=93,VolErrors=0,VolWrites=1439631,MaxVolBytes=26843545600,VolStatus='Append',Slot=0,InChanger=0,VolReadTime=0,VolWriteTime=39231914,LabelType=0,StorageId=3,PoolId=2,VolRetention=2592000,VolUseDuration=0,MaxVolJobs=0,MaxVolFiles=0,Enabled=1,LocationId=0,ScratchPoolId=0,RecyclePoolId=0,RecycleCount=3,Recycle=1,ActionOnPurge=1,MinBlocksize=0,MaxBlocksize=0 WHERE VolumeName='Incremental-0012'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (50): next_vol.c:283-5224 Vol=Incremental-0012 expired=0 bareos-dir (100): catreq.c:97-5224 Vol Info for backup-frontend-00-int.2017-07-17_15.12.29_09: 1000 OK VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=93 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:336-5224 >CatReq response: 1000 OK VolName=Incremental-0012 VolJobs=14 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=93 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:337-5224 Leave catreq jcr 0x30006f88 bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 340: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 UpdateMedia VolName=Incremental-0012 VolJobs=15 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=93 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 EndTime=1500293551 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 VolFirstWritten=0 bareos-dir (900): getmsg.c:254-5224 Catalog req jcr 0x30006f88: CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 UpdateMedia VolName=Incremental-0012 VolJobs=15 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=93 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 EndTime=1500293551 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 VolFirstWritten=0 bareos-dir (100): catreq.c:124-5224 catreq CatReq Job=backup-frontend-00-int.2017-07-17_15.12.29_09 UpdateMedia VolName=Incremental-0012 VolJobs=15 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=93 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 EndTime=1500293551 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 VolFirstWritten=0 bareos-dir (400): catreq.c:227-5224 Update media Incremental-0012 oldStat= newStat=Append bareos-dir (500): postgresql.c:622-5224 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,MinBlocksize,MaxBlocksize FROM Media WHERE VolumeName='Incremental-0012'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 40 fields bareos-dir (500): postgresql.c:658-5224 we have 1 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (500): postgresql.c:746-5224 sql_fetch_row start bareos-dir (500): postgresql.c:758-5224 we need space for 320 bytes bareos-dir (500): postgresql.c:772-5224 sql_fetch_row row number '0' is acceptable (0..1) bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '0' has value '12' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '1' has value 'Incremental-0012' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '2' has value '14' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '3' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '4' has value '191267' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '5' has value '12338415310' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '6' has value '93' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '7' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '8' has value '1439631' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '9' has value '26843545600' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '10' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '11' has value 'File' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '12' has value 'Append' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '13' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '14' has value '2592000' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '15' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '16' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '17' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '18' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '19' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '20' has value '2017-07-04 01:03:50' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '21' has value '2017-07-04 01:14:45' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '22' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '23' has value '2' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '24' has value '3748455028' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '25' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '26' has value '2017-07-04 01:03:29' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '27' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '28' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '29' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '30' has value '3' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '31' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '32' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '33' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '34' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '35' has value '39231914' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '36' has value '1' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '37' has value '' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '38' has value '0' bareos-dir (500): postgresql.c:778-5224 sql_fetch_row field '39' has value '0' bareos-dir (500): postgresql.c:789-5224 sql_fetch_row finishes returning 7ff62400f658 bareos-dir (400): catreq.c:273-5224 Update media: BefVolJobs=14 After=15 bareos-dir (400): catreq.c:317-5224 db_update_media_record. Stat=Append Vol=Incremental-0012 bareos-dir (100): sql_update.c:322-5224 update_media: FirstWritten=1499119430 bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'UPDATE Media Set LastWritten='2017-07-04 01:14:45' WHERE VolumeName='Incremental-0012'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (400): sql_update.c:385-5224 UPDATE Media SET VolJobs=15,VolFiles=2,VolBlocks=191267,VolBytes=12338415310,VolMounts=93,VolErrors=0,VolWrites=1439631,MaxVolBytes=26843545600,VolStatus='Append',Slot=0,InChanger=0,VolReadTime=0,VolWriteTime=39231914,LabelType=0,StorageId=3,PoolId=2,VolRetention=2592000,VolUseDuration=0,MaxVolJobs=0,MaxVolFiles=0,Enabled=1,LocationId=0,ScratchPoolId=0,RecyclePoolId=0,RecycleCount=3,Recycle=1,ActionOnPurge=1,MinBlocksize=0,MaxBlocksize=0 WHERE VolumeName='Incremental-0012' bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'UPDATE Media SET VolJobs=15,VolFiles=2,VolBlocks=191267,VolBytes=12338415310,VolMounts=93,VolErrors=0,VolWrites=1439631,MaxVolBytes=26843545600,VolStatus='Append',Slot=0,InChanger=0,VolReadTime=0,VolWriteTime=39231914,LabelType=0,StorageId=3,PoolId=2,VolRetention=2592000,VolUseDuration=0,MaxVolJobs=0,MaxVolFiles=0,Enabled=1,LocationId=0,ScratchPoolId=0,RecyclePoolId=0,RecycleCount=3,Recycle=1,ActionOnPurge=1,MinBlocksize=0,MaxBlocksize=0 WHERE VolumeName='Incremental-0012'' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir (50): next_vol.c:283-5224 Vol=Incremental-0012 expired=0 bareos-dir (100): catreq.c:97-5224 Vol Info for backup-frontend-00-int.2017-07-17_15.12.29_09: 1000 OK VolName=Incremental-0012 VolJobs=15 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=93 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:336-5224 >CatReq response: 1000 OK VolName=Incremental-0012 VolJobs=15 VolFiles=2 VolBlocks=191267 VolBytes=12338415310 VolMounts=93 VolErrors=0 VolWrites=1439631 MaxVolBytes=26843545600 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=0 VolWriteTime=39231914 EndFile=2 EndBlock=3748455028 LabelType=0 MediaId=12 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 bareos-dir (400): catreq.c:337-5224 Leave catreq jcr 0x30006f88 bareos-dir (200): getmsg.c:151-5224 bget_dirmsg 194: UpdCat Job=backup-frontend-00-int.2017-07-17_15.12.29_09 FileAttributes bareos-dir (900): getmsg.c:259-5224 Catalog upd jcr 0x30006f88: UpdCat Job=backup-frontend-00-int.2017-07-17_15.12.29_09 FileAttributes bareos-dir (400): catreq.c:449-5224 UpdCat msg=UpdCat Job=backup-frontend-00-int.2017-07-17_15.12.29_09 FileAttributes bareos-dir (400): catreq.c:451-5224 UpdCat VolSessId=46 VolSessT=1499018226 FI=1 Strm=1 reclen=102 bareos-dir (10): mem_pool.c:238-5224 ERROR: Failed ASSERT: obuf bareos-dir (850): message.c:858-5224 Enter dispatch_message type=4 msg=bareos-dir: ERROR in mem_pool.c:238 Failed ASSERT: obuf bareos-dir (850): message.c:1069-5224 MAIL for following msg: bareos-dir: ERROR in mem_pool.c:238 Failed ASSERT: obuf bareos-dir (850): message.c:975-5224 CONSOLE for following msg: bareos-dir: ERROR in mem_pool.c:238 Failed ASSERT: obuf bareos-dir (850): message.c:1097-5224 APPEND for following msg: bareos-dir: ERROR in mem_pool.c:238 Failed ASSERT: obuf bareos-dir (500): postgresql.c:622-5224 sql_query starts with 'INSERT INTO Log (JobId, Time, LogText) VALUES (5224,'2017-07-17 15:12:31','bareos-dir: ERROR in mem_pool.c:238 Failed ASSERT: obuf ')' bareos-dir (500): postgresql.c:649-5224 we have a result bareos-dir (500): postgresql.c:655-5224 we have 0 fields bareos-dir (500): postgresql.c:658-5224 we have 0 rows bareos-dir (500): postgresql.c:709-5224 sql_query finishing bareos-dir: mem_pool.c:238-5224 Failed ASSERT: obuf bareos-dir (900): signal.c:136-5224 sig=11 Segmentation violation bareos-dir (300): signal.c:206-5224 Working=/var/lib/bareos bareos-dir (300): signal.c:207-5224 btpath=/var/lib/bareos/btraceback bareos-dir (300): signal.c:208-5224 exepath=/var/lib/bareos/bareos-dir bareos-dir (500): signal.c:239-5224 Doing waitpid execv: /var/lib/bareos/btraceback failed: ERR=No such file or directory bareos-dir (500): signal.c:241-5224 Done waitpid